API tools faq
paste
Advertisement
fedorm

Untitled

fedorm
Oct 28th, 2019
588
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
XML 87.31 KB | None | 0 0
raw download clone embed print report
  1. 28.10 13:51:20.852 INFO  [CommonLogger] LoaderStart
  2. 28.10 13:51:20.852 INFO  [CommonLogger] JVM - 1.8.0_112
  3. 28.10 13:51:20.932 INFO  [CommonLogger] Loader start. Stage = 4
  4. 28.10 13:51:21.263 INFO  [CommonLogger] Executing command "sudo ln -sf /usr/local/share/zoneinfo/Europe/Moscow /etc/localtime"...
  5. 28.10 13:51:21.277 INFO  [CommonLogger] Waiting "sudo ln -sf /usr/local/share/zoneinfo/Europe/Moscow /etc/localtime" command to execute...
  6. 28.10 13:51:21.277 INFO  [CommonLogger] Process exited with code 0
  7. 28.10 13:51:21.277 INFO  [CommonLogger] Command response:
  8. 28.10 13:51:37.353 INFO  [PaymentsServiceImpl] PaymentsServiceImpl constructor called
  9. 28.10 13:51:38.186 INFO  [Set5LoyaltyEmulatorStarter] entering startSet5LoyaltyEmulator()
  10. 28.10 13:51:38.601 INFO  [Set5LoyaltyEmulatorStarter] Set5LoyaltyEmulator started!
  11. 28.10 13:51:38.601 INFO  [Set5LoyaltyEmulatorStarter] leaving startSet5LoyaltyEmulator()
  12. 28.10 13:51:52.760 INFO  [SMServiceLauncher] entering startSMServiceEmulator()
  13. 28.10 13:51:52.965 INFO  [SMServiceLauncher] leaving startSMServiceEmulator()
  14. 28.10 13:51:53.008 DEBUG [FileReader] Create FileReader OPERDAY_TO_CASH
  15. 28.10 13:51:53.060 DEBUG [SetApiShiftEventListener] class ru.crystals.pos.techprocess.SetApiShiftEventListener default constructor called
  16. 28.10 13:51:53.452 INFO  [KopilkaServiceLauncher] entering startKopilkaServiceEmulator()
  17. 28.10 13:51:53.970 TRACE [KopilkaServiceEmulatorTogglerImpl] entering disconnect()
  18. 28.10 13:51:54.367 TRACE [KopilkaServiceEmulatorSingleton] entering disconnect()
  19. 28.10 13:51:54.368 TRACE [KopilkaServiceEmulatorSingleton] leaving disconnect()
  20. 28.10 13:51:54.388 TRACE [KopilkaServiceEmulatorTogglerImpl] leaving disconnect()
  21. 28.10 13:51:54.388 TRACE [KopilkaServiceEmulatorTogglerImpl] entering connect()
  22. 28.10 13:51:54.446 TRACE [KopilkaServiceEmulatorSingleton] entering connect()
  23. 28.10 13:51:54.446 TRACE [KopilkaServiceEmulatorSingleton] leaving connect()
  24. 28.10 13:51:54.454 TRACE [KopilkaServiceEmulatorTogglerImpl] leaving connect()
  25. 28.10 13:51:54.454 INFO  [KopilkaServiceLauncher] leaving startKopilkaServiceEmulator()
  26. 28.10 13:51:55.809 TRACE [SoftCheckServiceLoader] Found softCheck service. It will provide soft cheque service
  27. 28.10 13:52:05.472 ERROR [GalyaClient]
  28. ru.crystals.setgalya.exceptions.SGClientRequestException: Unsuccessful login 404 page not found
  29.  status 404
  30.     at ru.crystals.setgalya.client.GalyaClient.login(GalyaClient.java:248) ~[set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  31.     at ru.crystals.setgalya.client.GalyaClient.<init>(GalyaClient.java:122) [set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  32.     at ru.crystals.setgalya.client.GalyaClient$GalyaClientBuilder.build(GalyaClient.java:104) [set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  33.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.initClient(SetGalyaBridgeImpl.java:72) [?:10.2.75.0]
  34.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.readExternalProcessingSettingsInto(SetGalyaBridgeImpl.java:277) [?:10.2.75.0]
  35.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.readExternalProcessingSettingsInto(SetGalyaBridgeImpl.java:27) [?:10.2.75.0]
  36.     at ru.crystals.pos.service.ExternalService.reloadSettings(ExternalService.java:127) [loyalty-cash-api.jar:10.2.75.0]
  37.     at ru.crystals.pos.service.ExternalService.getSettings(ExternalService.java:107) [loyalty-cash-api.jar:10.2.75.0]
  38.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.initClient(SetGalyaBridgeImpl.java:61) [?:10.2.75.0]
  39.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.init(SetGalyaBridgeImpl.java:57) [?:10.2.75.0]
  40.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112]
  41.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112]
  42.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112]
  43.     at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112]
  44.     at ru.crystals.bundles.DependencyRunnable.run(DependencyRunnable.java:35) [OSGiDataStruct.jar:10.2.75.0]
  45.     at ru.crystals.bundles.WaitMethod.run(WaitMethod.java:25) [OSGiDataStruct.jar:10.2.75.0]
  46.     at ru.crystals.bundles.BundleManager.runLevelCheck(BundleManager.java:731) [OSGiDataStruct.jar:10.2.75.0]
  47.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:716) [OSGiDataStruct.jar:10.2.75.0]
  48.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:571) [OSGiDataStruct.jar:10.2.75.0]
  49.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:538) [OSGiDataStruct.jar:10.2.75.0]
  50.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:401) [OSGiDataStruct.jar:10.2.75.0]
  51.     at ru.crystals.pos.properties.PropertiesManagerImpl.initPropertiesManager(PropertiesManagerImpl.java:104) [?:10.2.75.0]
  52.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112]
  53.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112]
  54.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112]
  55.     at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112]
  56.     at ru.crystals.bundles.DependencyRunnable.run(DependencyRunnable.java:35) [OSGiDataStruct.jar:10.2.75.0]
  57.     at ru.crystals.bundles.WaitMethod.run(WaitMethod.java:25) [OSGiDataStruct.jar:10.2.75.0]
  58.     at ru.crystals.bundles.BundleManager.scanDependencyInit(BundleManager.java:677) [OSGiDataStruct.jar:10.2.75.0]
  59.     at ru.crystals.bundles.BundleManager.startWithDependency(BundleManager.java:341) [OSGiDataStruct.jar:10.2.75.0]
  60.     at ru.crystals.bundles.BundleManager.makeInstance(BundleManager.java:307) [OSGiDataStruct.jar:10.2.75.0]
  61.     at ru.crystals.bundles.BundleManager.makeInstance(BundleManager.java:274) [OSGiDataStruct.jar:10.2.75.0]
  62.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:566) [OSGiDataStruct.jar:10.2.75.0]
  63.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:538) [OSGiDataStruct.jar:10.2.75.0]
  64.     at ru.crystals.bundles.BundleManagerLoader$1.initClass(BundleManagerLoader.java:38) [CashDS.jar:10.2.75.0]
  65.     at ru.crystals.bundles.BundleManagerLoader$1.initClass(BundleManagerLoader.java:24) [CashDS.jar:10.2.75.0]
  66.     at ru.crystals.bundles.ClassScanner.initClassesForAnnotation(ClassScanner.java:224) [PluginLoader.jar:10.2.75.0]
  67.     at ru.crystals.bundles.BundleManagerLoader.initContextBundles(BundleManagerLoader.java:24) [CashDS.jar:10.2.75.0]
  68.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:384) [loader.jar:10.2.75.0]
  69.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:97) [loader.jar:10.2.75.0]
  70.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[?:1.8.0_112]
  71.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) [?:1.8.0_112]
  72.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) [?:1.8.0_112]
  73.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423) [?:1.8.0_112]
  74.     at java.lang.Class.newInstance(Class.java:442) [?:1.8.0_112]
  75.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86) [loader.jar:10.2.75.0]
  76.     at ru.crystals.pos.loader.Loader.main(Loader.java:136) [loader.jar:10.2.75.0]
  77. 28.10 13:52:05.551 ERROR [GalyaClient]
  78. ru.crystals.setgalya.exceptions.SGClientRequestException: Unsuccessful login 404 page not found
  79.  status 404
  80.     at ru.crystals.setgalya.client.GalyaClient.login(GalyaClient.java:248) ~[set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  81.     at ru.crystals.setgalya.client.GalyaClient.<init>(GalyaClient.java:122) [set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  82.     at ru.crystals.setgalya.client.GalyaClient$GalyaClientBuilder.build(GalyaClient.java:104) [set-galya-http-client-1.0.0-20191018.132839-1.jar:1.0.0-SNAPSHOT]
  83.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.initClient(SetGalyaBridgeImpl.java:72) [?:10.2.75.0]
  84.     at ru.crystals.pos.setgalya.SetGalyaBridgeImpl.init(SetGalyaBridgeImpl.java:57) [?:10.2.75.0]
  85.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112]
  86.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112]
  87.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112]
  88.     at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112]
  89.     at ru.crystals.bundles.DependencyRunnable.run(DependencyRunnable.java:35) [OSGiDataStruct.jar:10.2.75.0]
  90.     at ru.crystals.bundles.WaitMethod.run(WaitMethod.java:25) [OSGiDataStruct.jar:10.2.75.0]
  91.     at ru.crystals.bundles.BundleManager.runLevelCheck(BundleManager.java:731) [OSGiDataStruct.jar:10.2.75.0]
  92.     at ru.crystals.bundles.BundleManager.sendMessages(BundleManager.java:716) [OSGiDataStruct.jar:10.2.75.0]
  93.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:571) [OSGiDataStruct.jar:10.2.75.0]
  94.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:538) [OSGiDataStruct.jar:10.2.75.0]
  95.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:401) [OSGiDataStruct.jar:10.2.75.0]
  96.     at ru.crystals.pos.properties.PropertiesManagerImpl.initPropertiesManager(PropertiesManagerImpl.java:104) [?:10.2.75.0]
  97.     at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112]
  98.     at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112]
  99.     at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112]
  100.     at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112]
  101.     at ru.crystals.bundles.DependencyRunnable.run(DependencyRunnable.java:35) [OSGiDataStruct.jar:10.2.75.0]
  102.     at ru.crystals.bundles.WaitMethod.run(WaitMethod.java:25) [OSGiDataStruct.jar:10.2.75.0]
  103.     at ru.crystals.bundles.BundleManager.scanDependencyInit(BundleManager.java:677) [OSGiDataStruct.jar:10.2.75.0]
  104.     at ru.crystals.bundles.BundleManager.startWithDependency(BundleManager.java:341) [OSGiDataStruct.jar:10.2.75.0]
  105.     at ru.crystals.bundles.BundleManager.makeInstance(BundleManager.java:307) [OSGiDataStruct.jar:10.2.75.0]
  106.     at ru.crystals.bundles.BundleManager.makeInstance(BundleManager.java:274) [OSGiDataStruct.jar:10.2.75.0]
  107.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:566) [OSGiDataStruct.jar:10.2.75.0]
  108.     at ru.crystals.bundles.BundleManager.add(BundleManager.java:538) [OSGiDataStruct.jar:10.2.75.0]
  109.     at ru.crystals.bundles.BundleManagerLoader$1.initClass(BundleManagerLoader.java:38) [CashDS.jar:10.2.75.0]
  110.     at ru.crystals.bundles.BundleManagerLoader$1.initClass(BundleManagerLoader.java:24) [CashDS.jar:10.2.75.0]
  111.     at ru.crystals.bundles.ClassScanner.initClassesForAnnotation(ClassScanner.java:224) [PluginLoader.jar:10.2.75.0]
  112.     at ru.crystals.bundles.BundleManagerLoader.initContextBundles(BundleManagerLoader.java:24) [CashDS.jar:10.2.75.0]
  113.     at ru.crystals.pos.loader.LoaderInner.initSpring(LoaderInner.java:384) [loader.jar:10.2.75.0]
  114.     at ru.crystals.pos.loader.LoaderInner.<init>(LoaderInner.java:97) [loader.jar:10.2.75.0]
  115.     at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[?:1.8.0_112]
  116.     at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) [?:1.8.0_112]
  117.     at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) [?:1.8.0_112]
  118.     at java.lang.reflect.Constructor.newInstance(Constructor.java:423) [?:1.8.0_112]
  119.     at java.lang.Class.newInstance(Class.java:442) [?:1.8.0_112]
  120.     at ru.crystals.pos.loader.Loader.<init>(Loader.java:86) [loader.jar:10.2.75.0]
  121.     at ru.crystals.pos.loader.Loader.main(Loader.java:136) [loader.jar:10.2.75.0]
  122. 28.10 13:52:05.947 TRACE [LoyaltyRestrictionsServiceImpl] Start service...
  123. 28.10 13:52:05.949 INFO  [LoyaltyRestrictionsServiceImpl] updating settings!
  124. 28.10 13:52:05.949 TRACE [LoyaltyRestrictionsServiceImpl] entering readLocalSettingsIntoObject()
  125. 28.10 13:52:05.954 TRACE [LoyaltyRestrictionsServiceImpl] leaving readLocalSettingsIntoObject(). the result is: LoyaltyRestrictionsConnectionSettings{serverUrl='/', connectionTimeout=10}
  126. 28.10 13:52:05.957 TRACE [LoyaltyRestrictionsServiceImpl] settings were reloaded. The result is: LoyaltyRestrictionsConnectionSettings{serverUrl='http://127.0.0.1:50052/v1/', connectionTimeout=10}
  127. 28.10 13:52:05.990 TRACE [LoyaltyRestrictionsServiceImpl] Loaded properties: LoyaltyRestrictionsConnectionSettings{serverUrl='http://127.0.0.1:50052/v1/', connectionTimeout=10}
  128. 28.10 13:52:06.924 INFO  [CheckService] Add hibernate property - hibernate.connection.driver_class = org.postgresql.Driver
  129. 28.10 13:52:06.925 INFO  [CheckService] Add hibernate property - hibernate.format_sql = true
  130. 28.10 13:52:06.925 INFO  [CheckService] Add hibernate property - hibernate.connection.provider_class = org.hibernate.connection.C3P0ConnectionProvider
  131. 28.10 13:52:06.925 INFO  [CheckService] Add hibernate property - hibernate.hbm2ddl.auto = false
  132. 28.10 13:52:06.927 INFO  [CheckService] Add hibernate property - hibernate.id.new_generator_mappings = false
  133. 28.10 13:52:06.927 INFO  [CheckService] Add hibernate property - hibernate.c3p0.max_statement = 10
  134. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.dialect = org.hibernate.dialect.PostgreSQLDialect
  135. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.c3p0.minPoolSize = 1
  136. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.cache.use_query_cache = false
  137. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.connection.password = postgres
  138. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.validator.apply_to_ddl = false
  139. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.connection.username = postgres
  140. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.search.autoregister_listeners = false
  141. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.connection.url = jdbc:postgresql://localhost:5432/cash
  142. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.cache.use_second_level_cache = false
  143. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.c3p0.timeout = 1800
  144. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.transaction.factory_class = org.hibernate.transaction.JDBCTransactionFactory
  145. 28.10 13:52:06.928 INFO  [CheckService] Add hibernate property - hibernate.show_sql = false
  146. 28.10 13:52:06.929 INFO  [CheckService] Add hibernate property - hibernate.c3p0.maxPoolSize = 5
  147. 28.10 13:52:06.930 INFO  [CheckService] Add hibernate property - hibernate.cglib.use_reflection_optimizer = false
  148. 28.10 13:52:06.930 INFO  [CheckService] Add hibernate property - hibernate.validator.autoregister_listeners = false
  149. 28.10 13:52:06.930 INFO  [CheckService] Add hibernate property - hibernate.current_session_context_class = thread
  150. 28.10 13:52:06.930 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.CashPaymentEntity
  151. 28.10 13:52:06.930 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  152. 28.10 13:52:06.930 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BankCardPaymentEntity
  153. 28.10 13:52:06.930 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BankCardPaymentTransactionEntity
  154. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentEntity
  155. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  156. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.CFTGiftCardPaymentEntity
  157. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  158. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.SiebelBonusesForGiftPaymentEntity
  159. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  160. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.GiftCardPaymentEntity
  161. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  162. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.SupraPaymentEntity
  163. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  164. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PrepaymentEntity
  165. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  166. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.CashMachinePaymentEntity
  167. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  168. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.CFTEGCPaymentEntity
  169. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  170. 28.10 13:52:06.931 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.ExternalBankTerminalPaymentEntity
  171. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  172. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BonusCardPaymentEntity
  173. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  174. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.ChildrenCardPaymentEntity
  175. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BankCardPaymentTransactionEntity
  176. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.KopilkaPaymentEntity
  177. 28.10 13:52:06.932 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  178. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.ConsumerCreditPaymentEntity
  179. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  180. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BonusSberbankPaymentEntity
  181. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  182. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.SiebelBonusCardPaymentEntity
  183. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.Empty
  184. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.UserEntity
  185. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ReportProductTypeEntity
  186. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.egais.logging.EgaisInteractionEntity
  187. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentPropertyNameEntity
  188. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseExtDataEntity
  189. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.SiebelBonusesForGiftPaymentEntity
  190. 28.10 13:52:06.933 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PropertyEntity
  191. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ManualPositionAdvertisingActionEntity
  192. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.IntroductionEntity
  193. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseStornoEntity
  194. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseExciseBottleEntity
  195. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ShiftEntity
  196. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionProductionDateEntity
  197. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.SessionEntity
  198. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.InventoryEntity
  199. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionSiebelGiftCardEntity
  200. 28.10 13:52:06.934 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionGiftCardEntity
  201. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.WithdrawalEntity
  202. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseCardsEntity
  203. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.feedback.FeedbackEntity
  204. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.CorrectionReceiptPaymentsEntity
  205. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseCardExtDataEntity
  206. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.DocumentEntity
  207. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.CancelPaymentReceiptEntity
  208. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionCFTGiftCardEntity
  209. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ManualAdvertisingActionEntity
  210. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionClothingEntity
  211. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BankCardCancelPaymentTransactionEntity
  212. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ReportShiftStornoEntity
  213. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.SellerPositionEntity
  214. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentTransactionEntity
  215. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.messanger.entity.MessageEntity
  216. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseEntity
  217. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentTransactionSlipEntity
  218. 28.10 13:52:06.935 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ReportShiftTaxesEntity
  219. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.CorrectionReceiptTaxesEntity
  220. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ShiftStatusData
  221. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionPropertyEntity
  222. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.messanger.entity.QueueSizeCashierAnswerEntity
  223. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.exist.entity.PositionExistBalanceEntity
  224. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionEntity
  225. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentEntity
  226. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionSpiritsEntity
  227. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ReportShiftEntity
  228. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.ReportPaymentTypeEntity
  229. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.CorrectionReceiptEntity
  230. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionDiscountCardEntity
  231. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionServiceEntity
  232. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.messanger.entity.RequestEntity
  233. 28.10 13:52:06.936 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PositionMobilePayEntity
  234. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.BankCardCancelPaymentTransactionSlipEntity
  235. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.messanger.entity.BarcodeNotFoundEntity
  236. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.messanger.entity.LogOutDateEntity
  237. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.PurchaseTaxesEntity
  238. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.check.MeasurePositionEntity
  239. 28.10 13:52:06.937 INFO  [CheckService] Add addon entity - ru.crystals.pos.payments.PaymentPropertyEntity
  240. 28.10 13:52:54.406 DEBUG [HibernateBackedLoyTxDao] building hibernate session factory
  241. 28.10 13:52:57.301 TRACE [JdbcBackedCashAdvertisingActionDao] entering postConstruct()
  242. 28.10 13:52:57.304 INFO  [JdbcBackedCashAdvertisingActionDao] creating jdbcMapper...
  243. 28.10 13:52:57.466 TRACE [HibernateBackedCashAdvertisingActionDao] building hibernate session factory
  244. 28.10 13:53:00.587 TRACE [JdbcBackedCashAdvertisingActionDao] leaving postConstruct()
  245. 28.10 13:53:11.027 TRACE [AdvActionsCacheImpl] starting AdvActionsCacheImpl initialization in a separate thread
  246. 28.10 13:53:11.082 TRACE [AdvActionsCacheImpl] reading all active actions..
  247. 28.10 13:53:11.083 TRACE [AdvActionsCacheImpl] initActionsCache: lock on cache was obtained in 0 [ms]
  248. 28.10 13:53:11.101 TRACE [AdvActionsCacheImpl] clearing cache..
  249. 28.10 13:53:11.101 TRACE [AdvActionsCacheImpl] cache cleared
  250. 28.10 13:53:11.102 TRACE [AdvActionsCacheImpl] entering initActionsCacheComplete()
  251. 28.10 13:53:11.103 TRACE [JdbcBackedCashAdvertisingActionDao] entering getActionsByGuids(Collection, Date). The arguments are: guids [null], date: 2019-10-28T13:53:11.102+0300
  252. 28.10 13:53:11.404 TRACE [ActionIntrospectorImpl] <ActionIntrospector> was registered!
  253. 28.10 13:53:11.441 TRACE [ActionIntrospectorImpl] getActionTriggeringCouponsFromDB: query to execute: "SELECT a."value", c.periodstart, c.periodfinish, c.guid FROM discounts_action_plugin_property AS a INNER JOIN discounts_action_plugin AS b ON a.plugin_id = b.id INNER JOIN discounts_advertisingactions AS c ON b.action_id = c.id WHERE a."name" = 'couponNumber' AND length(a."value") > 0      AND b.class_name = 'ru.crystalservice.setv6.discounts.plugins.CouponsCondition'"
  254. 28.10 13:53:12.476 TRACE [ActionIntrospectorImpl] leaving getActionTriggeringCouponsFromDB(). the result is: {46126832=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@5bb51efa], 25072017=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@716b8bd8], 22023029=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@d071], 46126842=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@60141dcf], 22023028=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@5c3cac38], 46126853=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@453d5fae], 19911234=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@654a3a57], 111020172=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@166c7da8], 111020171=[ru.crystals.pos.loyal.cash.service.ActionIntrospectorImpl$ActionRange@7877e1fe]}; it took 1035 [ms]
  255. 28.10 13:53:14.088 TRACE [JdbcBackedCashAdvertisingActionDao] actions (withou collections) were extracted in 2855 [ms]
  256. 28.10 13:53:14.184 TRACE [JdbcBackedCashAdvertisingActionDao] entering pullCollections(Collection). The argument is: actions [size: 139]
  257. 28.10 13:53:16.128 TRACE [JdbcBackedCashAdvertisingActionDao] plugins were extracted and mapped in 1054 [ms]
  258. 28.10 13:53:17.093 TRACE [JdbcBackedCashAdvertisingActionDao] plugins properties were extracted and mapped in 963 [ms]
  259. 28.10 13:53:20.302 TRACE [HibernateBackedCashAdvertisingActionDao] hibernate session factory was built in 22832 [ms]
  260. 28.10 13:53:20.863 TRACE [JdbcBackedCashAdvertisingActionDao] master actions were extracted and mapped in 3769 [ms]
  261. 28.10 13:53:21.910 TRACE [JdbcBackedCashAdvertisingActionDao] result types were mapped in and mapped 1046 [ms]
  262. 28.10 13:53:21.996 TRACE [EventActionsServiceImpl] entering init()
  263. 28.10 13:53:22.017 INFO  [EventActionsServiceImpl] updating settings!
  264. 28.10 13:53:22.062 TRACE [EventActionsServiceImpl] entering readLocalSettingsIntoObject()
  265. 28.10 13:53:22.126 TRACE [JdbcBackedCashAdvertisingActionDao] no labels were extracted
  266. 28.10 13:53:22.126 TRACE [JdbcBackedCashAdvertisingActionDao] leaving pullCollections(Collection). It took 7944 [ms]
  267. 28.10 13:53:22.166 TRACE [JdbcBackedCashAdvertisingActionDao] leaving getActionsByGuids(Collection, Date). The result size is: 139; it took 11063 [ms]
  268. 28.10 13:53:25.456 TRACE [EventActionsServiceImpl] leaving readLocalSettingsIntoObject(). the result is: ru.crystals.pos.loyalty.EventActionsConnectionSettings@1f3c17e
  269. 28.10 13:53:25.718 TRACE [EventActionsServiceImpl] settings were reloaded. The result is: ru.crystals.pos.loyalty.EventActionsConnectionSettings@1f3c17e
  270. 28.10 13:53:25.721 TRACE [EventActionsServiceImpl] leaving init()
  271. 28.10 13:53:26.968 INFO  [CommonLogger] (NixNativeUtils) ADD NTP server
  272. 28.10 13:53:26.977 INFO  [CommonLogger] Executing command "sudo mv ntp.sh /opt/ntp.sh"...
  273. 28.10 13:53:27.401 INFO  [CommonLogger] Waiting "sudo mv ntp.sh /opt/ntp.sh" command to execute...
  274. 28.10 13:53:27.547 INFO  [CommonLogger] Process exited with code 0
  275. 28.10 13:53:27.574 INFO  [CommonLogger] Command response:
  276. 28.10 13:53:27.574 INFO  [CommonLogger] Executing command "sudo /opt/ntp.sh"...
  277. 28.10 13:53:27.606 INFO  [CommonLogger] Waiting "sudo /opt/ntp.sh" command to execute...
  278. 28.10 13:53:27.636 INFO  [CommonLogger] Process exited with code 1
  279. 28.10 13:53:27.636 INFO  [CommonLogger] Command response:
  280. 28.10 13:53:27.644 INFO  [CommonLogger] Executing command "sudo chmod u+x /opt/ntp.sh"...
  281. 28.10 13:53:27.665 INFO  [CommonLogger] Waiting "sudo chmod u+x /opt/ntp.sh" command to execute...
  282. 28.10 13:53:27.666 INFO  [CommonLogger] Process exited with code 0
  283. 28.10 13:53:27.666 INFO  [CommonLogger] Command response:
  284. 28.10 13:53:27.666 INFO  [CommonLogger] Executing command "cash save"...
  285. 28.10 13:53:27.748 INFO  [CommonLogger] Waiting "cash save" command to execute...
  286. 28.10 13:53:27.686 ERROR [PluginPropertiesSerializer] Restoration of the object-property [name: plugin, class: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult, value: null] failed!
  287. java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  288.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  289.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  290.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  291.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  292.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  293.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  294.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  295.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  296.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  297.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  298.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  299.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  300.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  301.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  302.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  303.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  304.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  305.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  306. 28.10 13:53:27.750 ERROR [ActionPluginSerializer] failed to deserialize plugin
  307. java.lang.IllegalArgumentException: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  308.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:300) ~[DataStructsModule.jar:10.2.75.0]
  309.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  310.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  311.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  312.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  313.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  314.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  315.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  316.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  317.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  318.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  319.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  320. Caused by: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  321.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  322.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  323.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  324.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  325.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  326.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  327.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  328.     ... 11 more
  329. 28.10 13:53:27.757 ERROR [AdvActionsCacheImpl] INVALID action [AdvertisingActionEntity{id=9691, guid=83472, parentGuid=null, name='СЧЕТЧИК ПО СУММЕ ЧЕКА', mode=UNCONDITIONAL, worksAnytime=true, useRestrictions=true, priority=1070.0, masterActionGuids=[]}, guid: 83472] was detected: not all plugins recognzed?
  330. 28.10 13:53:28.502 INFO  [CommonLogger] Process exited with code 0
  331. 28.10 13:53:30.534 INFO  [CommonLogger] Command response:
  332. 28.10 13:53:32.301 ERROR [PluginPropertiesSerializer] Restoration of the object-property [name: plugin, class: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult, value: null] failed!
  333. java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  334.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  335.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  336.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  337.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  338.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  339.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  340.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  341.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  342.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  343.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  344.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  345.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  346.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  347.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  348.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  349.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  350.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  351.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  352. 28.10 13:53:32.311 ERROR [ActionPluginSerializer] failed to deserialize plugin
  353. java.lang.IllegalArgumentException: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  354.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:300) ~[DataStructsModule.jar:10.2.75.0]
  355.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  356.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  357.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  358.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  359.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  360.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  361.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  362.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  363.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  364.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  365.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  366. Caused by: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  367.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  368.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  369.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  370.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  371.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  372.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  373.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  374.     ... 11 more
  375. 28.10 13:53:32.316 ERROR [AdvActionsCacheImpl] INVALID action [AdvertisingActionEntity{id=9714, guid=82823, parentGuid=81227, name='Счетчик по количеству чеков Обнуляется раз в 13 недель', mode=AUTOMATIC, worksAnytime=false, useRestrictions=false, priority=10.0, masterActionGuids=[]}, guid: 82823] was detected: not all plugins recognzed?
  376. 28.10 13:53:32.661 ERROR [PluginPropertiesSerializer] Restoration of the object-property [name: plugin, class: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult, value: null] failed!
  377. java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  378.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  379.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  380.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  381.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  382.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  383.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  384.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  385.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  386.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  387.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  388.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  389.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  390.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  391.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  392.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  393.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  394.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  395.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  396. 28.10 13:53:32.664 ERROR [ActionPluginSerializer] failed to deserialize plugin
  397. java.lang.IllegalArgumentException: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  398.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:300) ~[DataStructsModule.jar:10.2.75.0]
  399.     at ru.crystalservice.setv6.discounts.utils.ActionPluginSerializer.restorePlugin(ActionPluginSerializer.java:89) ~[DataStructsModule.jar:10.2.75.0]
  400.     at ru.crystals.discounts.AdvertisingActionEntity.getDeserializedPlugins(AdvertisingActionEntity.java:629) ~[DataStructsModule.jar:10.2.75.0]
  401.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.validateAction(AdvActionsCacheImpl.java:440) ~[loyalty-cash.jar:10.2.75.0]
  402.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.getInvalidActions(AdvActionsCacheImpl.java:414) ~[loyalty-cash.jar:10.2.75.0]
  403.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.putActionsIntoCacheAndRemoveInvalidOnes(AdvActionsCacheImpl.java:392) ~[loyalty-cash.jar:10.2.75.0]
  404.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCacheComplete(AdvActionsCacheImpl.java:220) ~[loyalty-cash.jar:10.2.75.0]
  405.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.initActionsCache(AdvActionsCacheImpl.java:206) ~[loyalty-cash.jar:10.2.75.0]
  406.     at ru.crystals.pos.loyal.cash.service.AdvActionsCacheImpl.lambda$postConstruct$0(AdvActionsCacheImpl.java:71) ~[loyalty-cash.jar:10.2.75.0]
  407.     at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
  408.     at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
  409.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  410. Caused by: java.lang.ClassNotFoundException: ru.crystalservice.setv6.discounts.plugins.UpdateCounterActionResult
  411.     at java.net.URLClassLoader.findClass(URLClassLoader.java:381) ~[?:1.8.0_112]
  412.     at java.lang.ClassLoader.loadClass(ClassLoader.java:424) ~[?:1.8.0_112]
  413.     at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:331) ~[?:1.8.0_112]
  414.     at java.lang.ClassLoader.loadClass(ClassLoader.java:357) ~[?:1.8.0_112]
  415.     at java.lang.Class.forName0(Native Method) ~[?:1.8.0_112]
  416.     at java.lang.Class.forName(Class.java:264) ~[?:1.8.0_112]
  417.     at ru.crystalservice.setv6.discounts.utils.PluginPropertiesSerializer.restoreObject(PluginPropertiesSerializer.java:254) ~[DataStructsModule.jar:10.2.75.0]
  418.     ... 11 more
  419. 28.10 13:53:32.666 ERROR [AdvActionsCacheImpl] INVALID action [AdvertisingActionEntity{id=9697, guid=83099, parentGuid=79046, name='Счетчик по количеству товаров. Обнуляется раз в 13 недель', mode=AUTOMATIC, worksAnytime=false, useRestrictions=false, priority=10.0, masterActionGuids=[]}, guid: 83099] was detected: not all plugins recognzed?
  420. 28.10 13:53:33.423 TRACE [AdvActionsCacheImpl] leaving initActionsCacheComplete(). it took 22321 [ms]
  421. 28.10 13:53:33.423 INFO  [AdvActionsCacheImpl] Time of init actions cache: 22340 ms
  422. 28.10 13:53:56.842 TRACE [HibernateBackedLoyTxDao] entering getLoyTxesByStatus(Collection, int). The arguments are: statuses [[NO_SENT, WAIT_ACKNOWLEDGEMENT, SENT_ERROR]], maxResults [100]
  423. 28.10 13:53:57.392 INFO  [TransferManager] Nothing yet not processed on server to resend
  424. 28.10 13:53:58.556 TRACE [HibernateBackedLoyTxDao] [0] loy-tx records were extracted from the db
  425. 28.10 13:53:58.665 TRACE [HibernateBackedLoyTxDao] leaving getLoyTxesByStatus(Collection, int). The result is: []; it took 1834 [ms]
  426. 28.10 13:54:34.166 TRACE [SMServiceImpl] entering init()
  427. 28.10 13:54:35.513 DEBUG [FileReader] Start FileTransportReader OPERDAY_TO_CASH
  428. 28.10 13:54:35.522 TRACE [FileReader] creating instance of FileTransferManager
  429. 28.10 13:54:35.525 TRACE [FileReader] create & schedule timer
  430. 28.10 13:54:35.528 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  431. 28.10 13:54:35.536 TRACE [MLServiceImpl] entering init()
  432. 28.10 13:54:35.538 INFO  [MLServiceImpl] updating settings!
  433. 28.10 13:54:35.557 TRACE [MLServiceImpl] entering readLocalSettingsIntoObject()
  434. 28.10 13:54:35.621 TRACE [MLServiceImpl] leaving readLocalSettingsIntoObject(). the result is: ml-con-settings [partner-id: 10; unit-id: null; pos: null; read-timeout: 5000; login: pl1\Victoria_Set10; password: nXFLuQ503; services: []; prefixes: []; holder-mandatory: false; enabled cashes: null; fake-card-no: "null"; action-guid: 0; i-method: ADD]
  435. 28.10 13:54:35.651 TRACE [MLServiceImpl] settings were reloaded. The result is: ml-con-settings [partner-id: Victoria; unit-id: 3388; pos: 1; read-timeout: 5000; login: testLogin; password: testPassword; services: [http://127.0.0.1:60324/sap/manzana?wsdl]; prefixes: [26, <:]; holder-mandatory: false; enabled cashes: null; fake-card-no: "2612341234"; action-guid: 0; i-method: ADD]; processing is enabled
  436. 28.10 13:54:35.704 TRACE [MLServiceImpl] leaving init()
  437. 28.10 13:54:35.805 TRACE [SCService] entering init()
  438. 28.10 13:54:35.807 INFO  [SCService] updating settings!
  439. 28.10 13:54:35.808 TRACE [SCService] entering readLocalSettingsIntoObject()
  440. 28.10 13:54:35.823 TRACE [SCService] leaving readLocalSettingsIntoObject(). the result is: sc-con-settings [pos-no: null; read-timeout: 5000; login: pl1\Victoria_Set10; password: nXFLuQ503; services: []; coupon-prefixes: []; enabled cashes: null]
  441. 28.10 13:54:35.845 TRACE [SCService] settings were reloaded. The result is: sc-con-settings [pos-no: 1; read-timeout: 2000; login: test-login; password: test-password; services: [http://127.0.0.1:8888/smch/emulator?wsdl, http://127.0.0.1:8888/smch/emulator?wsdl]; coupon-prefixes: [CL, 000000000000020939844]; enabled cashes: null]; processing is enabled
  442. 28.10 13:54:35.847 INFO  [SCService] looking up LoyFeedbackDao...
  443. 28.10 13:54:35.847 TRACE [SCService] leaving init()
  444. 28.10 13:54:36.638 INFO  [FiscalPrinterProxy] Try start provider for inn: null
  445. 28.10 13:54:36.690 INFO  [FiscalPrinter] Manual Exception has been restored. Exception has been canceled
  446. 28.10 13:54:36.889 DEBUG [AbstractFiscalPrinterEmulator] eth0
  447. 28.10 13:54:36.889 DEBUG [AbstractFiscalPrinterEmulator] InetAddress: 172.29.17.13
  448. 28.10 13:54:36.891 DEBUG [AbstractFiscalPrinterEmulator] java.rmi.server.hostname: 172.29.17.13
  449. 28.10 13:54:37.368 INFO  [AbstractFiscalPrinterEmulator] RMI Listening port: 8890
  450. 28.10 13:54:37.407 INFO  [FiscalPrinter] ---- FISCAL MODULE START ---- INN null -> true
  451. 28.10 13:54:37.651 INFO  [FiscalPrinter] Fiscal printer date: 2019-10-28T13:54:37.651+0300        current date: 2019-10-28T13:54:37.651+0300
  452. 28.10 13:54:37.653 INFO  [FiscalPrinter] Set requisites for inn: 7802781104
  453. 28.10 13:54:37.694 INFO  [FiscalPrinter] Set requisites - shop name: jr. name
  454. 28.10 13:54:37.695 INFO  [FiscalPrinter] Set requisites - shop address: 199100, Spb, Savushkina, 112
  455. 28.10 13:54:40.325 INFO  [FiscalPrinter] getRegNum
  456. 28.10 13:54:40.326 INFO  [FiscalPrinter] RegNum = NFM.3388.1.0.1571827454607
  457. 28.10 13:54:47.867 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  458. 28.10 13:54:47.989 TRACE [FileReader] getting new file..
  459. 28.10 13:54:48.026 TRACE [FileReader] No new file
  460. 28.10 13:54:48.026 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  461. 28.10 13:54:54.807 TRACE [SoftCheckService] entering init()
  462. 28.10 13:54:54.812 INFO  [SoftCheckService] updating settings!
  463. 28.10 13:54:54.821 TRACE [SoftCheckService] entering readLocalSettingsIntoObject()
  464. 28.10 13:54:54.842 TRACE [SoftCheckService] leaving readLocalSettingsIntoObject(). the result is: SoftCheckSettings [serviceAddress='localhost', barcodePrefix='707', cutPrefixCount=0, cutPrefix=false, numberLength=9, connectionTimeout=30000, port=8070, exciseAlcoholAllowed=false, fullServicePath='http://localhost:8070', requestAttemptCount='0', batchSize='10', delayStart='30000', period='30000', requestByShop='false', phoneCodes='[7, 8]', phoneLength='10', addPurchaseInfo=false]
  465. 28.10 13:54:55.076 TRACE [SoftCheckService] settings were reloaded. The result is: SoftCheckSettings [serviceAddress='localhost:8070', barcodePrefix='DP', cutPrefixCount=0, cutPrefix=false, numberLength=9, connectionTimeout=30000, port=8070, exciseAlcoholAllowed=false, fullServicePath='http://localhost:8070', requestAttemptCount='0', batchSize='10', delayStart='30000', period='30000', requestByShop='false', phoneCodes='[7, 8]', phoneLength='10', addPurchaseInfo=false]
  466. 28.10 13:54:55.077 TRACE [SoftCheckService] leaving init()
  467. 28.10 13:54:56.518 DEBUG [SoftCheckService] Service started. Settings SoftCheckSettings [serviceAddress='localhost:8070', barcodePrefix='DP', cutPrefixCount=0, cutPrefix=false, numberLength=9, connectionTimeout=30000, port=8070, exciseAlcoholAllowed=false, fullServicePath='http://localhost:8070', requestAttemptCount='0', batchSize='10', delayStart='30000', period='30000', requestByShop='false', phoneCodes='[7, 8]', phoneLength='10', addPurchaseInfo=false]
  468. 28.10 13:54:58.042 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  469. 28.10 13:54:58.174 TRACE [FileReader] getting new file..
  470. 28.10 13:54:58.256 TRACE [FileReader] No new file
  471. 28.10 13:54:58.256 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  472. 28.10 13:54:58.295 INFO  [SetApiShiftEventListener] class ru.crystals.pos.techprocess.SetApiShiftEventListener init method called
  473. 28.10 13:54:58.730 TRACE [HibernateBackedLoyTxDao] entering getLoyTxesByStatus(Collection, int). The arguments are: statuses [[NO_SENT, WAIT_ACKNOWLEDGEMENT, SENT_ERROR]], maxResults [100]
  474. 28.10 13:55:04.036 TRACE [HibernateBackedLoyTxDao] [0] loy-tx records were extracted from the db
  475. 28.10 13:55:04.398 TRACE [HibernateBackedLoyTxDao] leaving getLoyTxesByStatus(Collection, int). The result is: []; it took 5669 [ms]
  476. 28.10 13:55:08.344 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  477. 28.10 13:55:08.356 TRACE [FileReader] getting new file..
  478. 28.10 13:55:08.416 TRACE [FileReader] No new file
  479. 28.10 13:55:08.416 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  480. 28.10 13:55:11.069 ERROR [CashMachinePaymentController] Fail to get cash machine inventory. Cash machine is not initialized.
  481. 28.10 13:55:11.156 INFO  [CashMachinePaymentController] denomination auto detected value is 1
  482. 28.10 13:55:14.983 INFO  [PaymentsServiceImpl] Add external payment plugin: foo.service.payment
  483. 28.10 13:55:17.418 INFO  [CommonLogger] ---{ START OF MODULE }---
  484. 28.10 13:55:17.860 INFO  [KeyboardConfigLoader] Keyboard loaded - qwerty , config/plugins/keyboard-qwerty-0-kbd.xml
  485. 28.10 13:55:18.226 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  486. 28.10 13:55:18.226 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  487. 28.10 13:55:18.226 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  488. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  489. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  490. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  491. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  492. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  493. 28.10 13:55:18.227 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  494. 28.10 13:55:18.228 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  495. 28.10 13:55:18.228 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  496. 28.10 13:55:18.228 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  497. 28.10 13:55:18.228 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  498. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  499. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  500. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  501. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  502. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  503. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  504. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  505. 28.10 13:55:18.229 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  506. 28.10 13:55:18.230 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  507. 28.10 13:55:18.230 WARN  [KeyboardLayoutLoader] Element kbdAlphaNumeric/Ch of keyboard layout is empty
  508. 28.10 13:55:18.604 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  509. 28.10 13:55:18.670 TRACE [FileReader] getting new file..
  510. 28.10 13:55:18.738 TRACE [FileReader] No new file
  511. 28.10 13:55:18.865 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  512. 28.10 13:55:19.221 INFO  [FiscalPrinter] getFactoryNum
  513. 28.10 13:55:19.226 INFO  [FiscalPrinter] FactoryNum = 0000033881
  514. 28.10 13:55:19.227 INFO  [FiscalPrinter] getRegNum
  515. 28.10 13:55:19.228 INFO  [FiscalPrinter] RegNum = NFM.3388.1.0.1571827454607
  516. 28.10 13:55:19.228 INFO  [FiscalPrinter] getEklzNum
  517. 28.10 13:55:19.229 INFO  [FiscalPrinter] EklzNum = 6de03059-181e-4147-b738-705af76216f2
  518. 28.10 13:55:19.255 DEBUG [TechProcessImpl] Server online mode
  519. 28.10 13:55:19.309 INFO  [FiscalPrinter] getVerBios
  520. 28.10 13:55:19.310 INFO  [FiscalPrinter] VerBios = 27
  521. 28.10 13:55:19.179 ERROR [ExternalEncryptedEventPacket] Thread-46   Ошибка расшифровки пакета
  522. java.security.InvalidKeyException: Unwrapping failed
  523.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:445) ~[sunjce_provider.jar:1.8.0_112]
  524.     at javax.crypto.Cipher.unwrap(Cipher.java:2550) ~[?:1.8.0_121]
  525.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.encryptAesKey(ExternalPacketCipher.java:57) ~[prismaBridge.jar:10.2.75.0]
  526.     at ru.crystals.pos.prismabridge.external.ExternalPacketCipher.decode(ExternalPacketCipher.java:48) ~[prismaBridge.jar:10.2.75.0]
  527.     at ru.crystals.pos.prismabridge.external.ExternalEncryptedEventPacket.parseEvent(ExternalEncryptedEventPacket.java:44) [prismaBridge.jar:10.2.75.0]
  528.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.decodePacket(PrismaEmulatorRunnable.java:168) [PrismaEmulator.jar:?]
  529.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.readBytes(PrismaEmulatorRunnable.java:91) [PrismaEmulator.jar:?]
  530.     at ru.crystals.pos.emulator.prisma.PrismaEmulatorRunnable.run(PrismaEmulatorRunnable.java:71) [PrismaEmulator.jar:?]
  531.     at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
  532. Caused by: javax.crypto.BadPaddingException: Decryption error
  533.     at sun.security.rsa.RSAPadding.unpadV15(RSAPadding.java:380) ~[?:1.8.0_112]
  534.     at sun.security.rsa.RSAPadding.unpad(RSAPadding.java:291) ~[?:1.8.0_112]
  535.     at com.sun.crypto.provider.RSACipher.doFinal(RSACipher.java:363) ~[sunjce_provider.jar:1.8.0_112]
  536.     at com.sun.crypto.provider.RSACipher.engineUnwrap(RSACipher.java:440) ~[sunjce_provider.jar:1.8.0_112]
  537.     ... 8 more
  538. 28.10 13:55:19.417 INFO  [CheckServiceLoaderImpl] ---- PRIMARY CHECK MODULE FiscalVO{factoryNum=0000033881, fiscalNum=NFM.3388.1.0.1571827454607, eklzNum=6de03059-181e-4147-b738-705af76216f2, innNum=7802781104, hardwareName=Fiscal printer emulator 0, fiscalDate=28.09.2019, fwVersion=27} ----
  539. 28.10 13:55:19.422 INFO  [FiscalPrinter] getRegNum
  540. 28.10 13:55:19.423 INFO  [FiscalPrinter] RegNum = NFM.3388.1.0.1571827454607
  541. 28.10 13:55:19.423 INFO  [FiscalPrinter] getShiftNumber
  542. 28.10 13:55:19.425 INFO  [FiscalPrinter] ShiftNumber = 5
  543. 28.10 13:55:19.426 INFO  [FiscalPrinter] isShiftOpen
  544. 28.10 13:55:19.427 INFO  [FiscalPrinter] getLastKpk
  545. 28.10 13:55:19.428 INFO  [FiscalPrinter] LastKpk = 7
  546. 28.10 13:55:19.429 INFO  [FiscalPrinter] getCountCashIn
  547. 28.10 13:55:19.429 INFO  [FiscalPrinter] CountCashIn = 0
  548. 28.10 13:55:19.429 INFO  [FiscalPrinter] getCountCashOut
  549. 28.10 13:55:19.429 INFO  [FiscalPrinter] CountCashOut = 0
  550. 28.10 13:55:19.429 INFO  [FiscalPrinter] getCountAnnul
  551. 28.10 13:55:19.429 INFO  [FiscalPrinter] CountAnnul = 0
  552. 28.10 13:55:19.430 INFO  [FiscalPrinter] getSPND
  553. 28.10 13:55:19.430 INFO  [FiscalPrinter] CountSPND = 11
  554. 28.10 13:55:19.430 INFO  [FiscalPrinter] getCashAmount
  555. 28.10 13:55:19.430 INFO  [FiscalPrinter] CashAmount = 14166
  556. 28.10 13:55:19.492 INFO  [FiscalPrinter] ru.crystals.pos.check.ShiftStatusData@df4a72[regNum=NFM.3388.1.0.1571827454607,shiftNum=5,isShiftOpen=true,lastKpk=7,countCashIn=0,countCashOut=0,countAnnul=0,spnd=11,countPurchases=<null>,docToRecover=<null>,lastCloseDocument=FiscalDocumentData{type=SALE, numPurchase=7, numDocument=11, summ=10757, numFD=7},shiftClosurePending=true,cashAmount=14166,id=<null>]
  557. 28.10 13:55:19.565 TRACE [TechProcessShift] entering getLastShift()
  558. 28.10 13:55:22.133 TRACE [TechProcessShift] leaving getLastShift(). the result [found locally] is: ShiftEntity [cashNum=1, eklzNum=6de03059-181e-4147-b738-705af76216f2, fiscalNum=NFM.3388.1.0.1571827454607, fiscalSum=null, numShift=5, shiftClose=null, shiftOpen=null, toString()=ru.crystals.pos.check.ShiftEntity@280]; it took 2567 [ms]
  559. 28.10 13:55:22.139 INFO  [TechProcessShift] fiscalRegNum = FiscalVO{factoryNum=0000033881, fiscalNum=NFM.3388.1.0.1571827454607, eklzNum=6de03059-181e-4147-b738-705af76216f2, innNum=7802781104, hardwareName=Fiscal printer emulator 0, fiscalDate=28.09.2019, fwVersion=27}
  560. 28.10 13:55:23.539 INFO  [TechProcessShift] Смены совпадают, ожидается синхронизация по документам и счетчикам.
  561. 28.10 13:55:23.541 INFO  [TechProcessShift] Счетчики смен совпадают.
  562. 28.10 13:55:23.549 INFO  [TechProcessShift] Синхронизация пройдена.
  563. 28.10 13:55:23.683 WARN  [SessionNormalizer] Last session id=720 has not been end properly (no end date)
  564. 28.10 13:55:23.822 INFO  [SessionNormalizer] Last session end time will be set from lastWorkTime (2019-10-28T13:51:10)
  565. 28.10 13:55:23.959 INFO  [TransferManager] OperDayMessanger - UserLogOut
  566. 28.10 13:55:24.211 WARN  [TechProcessShift] Некорректный документ для восстановления:PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  567. 28.10 13:55:24.485 TRACE [CheckService] entering restoreNonFiscalChecks()
  568. 28.10 13:55:24.490 WARN  [CheckService] restoring non-fiscalized receipt [id: 680]
  569. 28.10 13:55:24.490 TRACE [CheckService] entering setCheckToWork(Long, boolean). the arguments are: idPurchase [680], notifyListeners [true]
  570. 28.10 13:55:24.621 DEBUG [ChecksHandler] Setting check: PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]
  571. 28.10 13:55:24.623 TRACE [TechProcessImpl] entering updateCheck(PurchaseEntity, int). The arguments are: check [PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]], checkNum [0]
  572. 28.10 13:55:24.665 TRACE [TechProcessImpl] leaving updateCheck(PurchaseEntity, int)
  573. 28.10 13:55:24.666 TRACE [CheckService] leaving setCheckToWork(Long, boolean)
  574. 28.10 13:55:24.666 TRACE [CheckService] leaving restoreNonFiscalChecks()
  575. 28.10 13:55:25.586 TRACE [TechProcessImpl] fillProductEntity: ProductEntity was found by marking [00818]
  576. 28.10 13:55:25.649 TRACE [TechProcessImpl] fillProductEntity: ProductEntity was found by marking [00515]
  577. 28.10 13:55:25.653 TRACE [TechProcessImpl] fillProductEntity: ProductEntity was found by marking [00616]
  578. 28.10 13:55:25.655 TRACE [TechProcessImpl] fillProductEntity: ProductEntity was found by marking [00888]
  579. 28.10 13:55:25.658 TRACE [TechProcessImpl] fillProductEntity: ProductEntity was found by marking [00919]
  580. 28.10 13:55:26.551 TRACE [HibernateBackedLoyTxDao] entering getLoyTxByReceipt(PurchaseEntity). The argument is: purchase  [PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]]
  581. 28.10 13:55:26.556 TRACE [HibernateBackedLoyTxDao] loy-tx-id of the receipt [PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]] IS NULL
  582. 28.10 13:55:26.557 WARN  [HibernateBackedLoyTxDao] leaving getLoyTxByReceipt(PurchaseEntity): at least one of the mandaroty fields (either doc-num: null, or operation-type: true, or shop-num: null, or shift-num: null, or cash-num: null) of the receipt [PurchaseEntity [id=680, number=null, dateCreate=2019-10-28 12:45:38.812, dateCommit=null, fiscalDocNum=null, sentToServerStatus=NO_SENT]] is NULL! So, NULL will be returned!
  583. 28.10 13:55:26.557 TRACE [TechProcessShift] entering TP.findNotCommitedChecks()
  584. 28.10 13:55:26.559 TRACE [TechProcessShift] TP.findNotCommitedChecks(): current shift is: ShiftEntity [cashNum=1, eklzNum=6de03059-181e-4147-b738-705af76216f2, fiscalNum=NFM.3388.1.0.1571827454607, fiscalSum=null, numShift=5, shiftClose=null, shiftOpen=null, toString()=ru.crystals.pos.check.ShiftEntity@280]
  585. 28.10 13:55:26.579 TRACE [TechProcessShift] leaving TP.findNotCommitedChecks()
  586. 28.10 13:55:26.635 TRACE [KeyboardImpl] creating KLocker. timeout = 20 [ms], contact-bounce-time = 100 [ms]
  587. 28.10 13:55:30.058 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  588. 28.10 13:55:30.666 TRACE [FileReader] getting new file..
  589. 28.10 13:55:31.030 TRACE [FileReader] No new file
  590. 28.10 13:55:31.033 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  591. 28.10 13:55:34.405 INFO  [SetApiLoyaltyPluginBackgroundWorker] Cards pending operation sender started
  592. 28.10 13:55:34.411 DEBUG [SetApiLoyaltyPluginBackgroundWorker] Polling...
  593. 28.10 13:55:34.411 INFO  [PendingOperationQueue] Pending cards operation queue is empty. Populating from DB...
  594. 28.10 13:55:34.768 INFO  [PendingOperationQueue] No pending card operations found.
  595. 28.10 13:55:34.981 INFO  [SetApiPluginLoyProvider] class ru.crystals.pos.loyal.SetApiPluginLoyProvider started
  596. 28.10 13:55:40.411 DEBUG [HibernateBackedActionsTransportAuxiliariesDao] building hibernate session factory
  597. 28.10 13:55:40.616 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  598. 28.10 13:55:41.038 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  599. 28.10 13:55:41.040 TRACE [FileReader] getting new file..
  600. 28.10 13:55:41.177 TRACE [FileReader] No new file
  601. 28.10 13:55:41.178 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  602. 28.10 13:55:41.262 TRACE [JdbcBackedLoyFeedbackDao] entering postConstruct()
  603. 28.10 13:55:41.264 INFO  [JdbcBackedLoyFeedbackDao] creating jdbcMapper...
  604. 28.10 13:55:42.287 TRACE [JdbcBackedLoyFeedbackDao] leaving postConstruct()
  605. 28.10 13:55:42.775 TRACE [AbstractCleaner] entering start()
  606. 28.10 13:55:42.786 INFO  [AbstractCleaner] <InternalCashPoolExecutor> was added. Starting the cleaner thread
  607. 28.10 13:55:42.883 INFO  [AbstractCleaner] cleaner thread was scheduled [initial-delay: 30; interval: 3600; to-remove-at-once: 1000]
  608. 28.10 13:55:42.884 TRACE [AbstractCleaner] leaving start()
  609. 28.10 13:55:43.144 TRACE [AbstractCleaner] entering start()
  610. 28.10 13:55:43.144 INFO  [AbstractCleaner] <InternalCashPoolExecutor> was added. Starting the cleaner thread
  611. 28.10 13:55:43.534 INFO  [AbstractCleaner] cleaner thread was scheduled [initial-delay: 30; interval: 3600; to-remove-at-once: 1000]
  612. 28.10 13:55:43.539 TRACE [AbstractCleaner] leaving start()
  613. 28.10 13:55:49.260 DEBUG [TechProcessImpl] Server online mode
  614. 28.10 13:55:50.678 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  615. 28.10 13:55:50.850 INFO  [CommonLogger] Executing command "sudo test-pcx"...
  616. 28.10 13:55:51.214 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  617. 28.10 13:55:51.367 TRACE [FileReader] getting new file..
  618. 28.10 13:55:51.381 INFO  [CommonLogger] Waiting "sudo test-pcx" command to execute...
  619. 28.10 13:55:51.481 TRACE [FileReader] No new file
  620. 28.10 13:55:51.483 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  621. 28.10 13:55:52.888 INFO  [CommonLogger] Process exited with code 0
  622. 28.10 13:55:52.937 INFO  [CommonLogger] Command response:
  623. 28.10 13:55:52.938 INFO  [CommonLogger] ----Разбор аргументов командной строки-----------
  624. 28.10 13:55:52.938 INFO  [CommonLogger] Демонстрационный тест выполнения операций через PCX
  625. 28.10 13:55:52.938 INFO  [CommonLogger] Будут выполнены :
  626. 28.10 13:55:52.938 INFO  [CommonLogger]   - эхо запрос к ПЦ
  627. 28.10 13:55:52.938 INFO  [CommonLogger]   - запрос состояния счета бонусной карты
  628. 28.10 13:55:52.938 INFO  [CommonLogger]   - оплата товара баллами
  629. 28.10 13:55:52.938 INFO  [CommonLogger]   - операция начисления баллов
  630. 28.10 13:55:52.938 INFO  [CommonLogger]   - отмена операции оплаты баллов
  631. 28.10 13:55:52.938 INFO  [CommonLogger]   - отмена операции начисления баллов
  632. 28.10 13:55:52.938 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]
  633. 28.10 13:55:52.938 INFO  [CommonLogger] ----Инициализация PCX----------------------------------------------
  634. 28.10 13:55:52.938 INFO  [CommonLogger] Executing command "test-pcx"...
  635. 28.10 13:55:53.188 INFO  [CommonLogger] Waiting "test-pcx" command to execute...
  636. 28.10 13:55:53.498 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 2823 [ms]
  637. 28.10 13:55:53.846 TRACE [ActionsFilesReader] no new files; last id = 85909
  638. 28.10 13:55:53.846 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  639. 28.10 13:55:54.276 INFO  [CommonLogger] Process exited with code 0
  640. 28.10 13:55:54.277 INFO  [CommonLogger] Command response:
  641. 28.10 13:55:54.277 INFO  [CommonLogger] ----Разбор аргументов командной строки-----------
  642. 28.10 13:55:54.277 INFO  [CommonLogger] Демонстрационный тест выполнения операций через PCX
  643. 28.10 13:55:54.277 INFO  [CommonLogger] Будут выполнены :
  644. 28.10 13:55:54.277 INFO  [CommonLogger]   - эхо запрос к ПЦ
  645. 28.10 13:55:54.277 INFO  [CommonLogger]   - запрос состояния счета бонусной карты
  646. 28.10 13:55:54.278 INFO  [CommonLogger]   - оплата товара баллами
  647. 28.10 13:55:54.278 INFO  [CommonLogger]   - операция начисления баллов
  648. 28.10 13:55:54.278 INFO  [CommonLogger]   - отмена операции оплаты баллов
  649. 28.10 13:55:54.278 INFO  [CommonLogger]   - отмена операции начисления баллов
  650. 28.10 13:55:54.278 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]
  651. 28.10 13:55:54.278 INFO  [CommonLogger] ----Инициализация PCX----------------------------------------------
  652. 28.10 13:55:54.278 INFO  [CommonLogger] Объект PCX создан !
  653. 28.10 13:55:55.192 ERROR [CFTBridgeImpl] Error loading CFT bridge module: Ошибка инициализации SSL:
  654. 28.10 13:55:58.014 INFO  [TransferManager] Nothing yet not processed on server to resend
  655. 28.10 13:55:59.704 INFO  [CommonLogger] Executing command "sudo test-pcx"...
  656. 28.10 13:55:59.839 INFO  [CommonLogger] Waiting "sudo test-pcx" command to execute...
  657. 28.10 13:56:01.073 INFO  [CommonLogger] Process exited with code 0
  658. 28.10 13:56:01.112 INFO  [CommonLogger] Command response:
  659. 28.10 13:56:01.112 INFO  [CommonLogger] ----Разбор аргументов командной строки-----------
  660. 28.10 13:56:01.112 INFO  [CommonLogger] Демонстрационный тест выполнения операций через PCX
  661. 28.10 13:56:01.129 INFO  [CommonLogger] Будут выполнены :
  662. 28.10 13:56:01.129 INFO  [CommonLogger]   - эхо запрос к ПЦ
  663. 28.10 13:56:01.129 INFO  [CommonLogger]   - запрос состояния счета бонусной карты
  664. 28.10 13:56:01.129 INFO  [CommonLogger]   - оплата товара баллами
  665. 28.10 13:56:01.129 INFO  [CommonLogger]   - операция начисления баллов
  666. 28.10 13:56:01.129 INFO  [CommonLogger]   - отмена операции оплаты баллов
  667. 28.10 13:56:01.129 INFO  [CommonLogger]   - отмена операции начисления баллов
  668. 28.10 13:56:01.129 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]
  669. 28.10 13:56:01.129 INFO  [CommonLogger] ----Инициализация PCX----------------------------------------------
  670. 28.10 13:56:01.132 INFO  [CommonLogger] Executing command "test-pcx"...
  671. 28.10 13:56:01.175 INFO  [CommonLogger] Waiting "test-pcx" command to execute...
  672. 28.10 13:56:01.604 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  673. 28.10 13:56:01.734 TRACE [FileReader] getting new file..
  674. 28.10 13:56:01.819 TRACE [FileReader] No new file
  675. 28.10 13:56:01.821 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  676. 28.10 13:56:02.662 INFO  [CommonLogger] Process exited with code 0
  677. 28.10 13:56:02.749 INFO  [CommonLogger] Command response:
  678. 28.10 13:56:03.444 ERROR [CFTBridgeImpl] Error loading CFT bridge module: Ошибка инициализации SSL:
  679. 28.10 13:56:03.850 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  680. 28.10 13:56:03.917 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 67 [ms]
  681. 28.10 13:56:04.025 TRACE [ActionsFilesReader] no new files; last id = 85909
  682. 28.10 13:56:04.027 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  683. 28.10 13:56:04.742 TRACE [HibernateBackedLoyTxDao] entering getLoyTxesByStatus(Collection, int). The arguments are: statuses [[NO_SENT, WAIT_ACKNOWLEDGEMENT, SENT_ERROR]], maxResults [100]
  684. 28.10 13:56:05.185 TRACE [HibernateBackedLoyTxDao] [0] loy-tx records were extracted from the db
  685. 28.10 13:56:05.186 TRACE [HibernateBackedLoyTxDao] leaving getLoyTxesByStatus(Collection, int). The result is: []; it took 444 [ms]
  686. 28.10 13:56:11.833 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  687. 28.10 13:56:11.852 TRACE [FileReader] getting new file..
  688. 28.10 13:56:11.862 TRACE [FileReader] No new file
  689. 28.10 13:56:11.863 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  690. 28.10 13:56:12.902 TRACE [LoyTxCleanerWorkhorse] entering run()
  691. 28.10 13:56:12.906 INFO  [LoyTxCleanerWorkhorse] shopNo: 3388
  692. 28.10 13:56:12.906 INFO  [LoyTxCleanerWorkhorse] cashNo: 1
  693. 28.10 13:56:12.906 INFO  [LoyTxCleanerWorkhorse] shiftsToKeep: 0
  694. 28.10 13:56:12.907 INFO  [LoyTxCleanerWorkhorse] inn: 7802781104
  695. 28.10 13:56:12.908 TRACE [LoyTxCleanerWorkhorse] leaving run(): seems the feature (trim-off superfluous docs) is disabled. shifts-to-keep: 0
  696. 28.10 13:56:13.535 TRACE [ActionsCleanerWorkhorse] entering run()
  697. 28.10 13:56:13.536 INFO  [ActionsCleanerWorkhorse] looking up <LoyTxDao>
  698. 28.10 13:56:13.539 TRACE [JdbcBackedCashAdvertisingActionDao] entering removeStaleActions(int). The argument maxRecordsToRemoveAtOnce is: 1000
  699. 28.10 13:56:14.038 TRACE [JdbcBackedCashAdvertisingActionDao] leaving removeStaleActions(int). The result deleted is: 0; it took 500 [ms]
  700. 28.10 13:56:14.039 TRACE [ActionsCleanerWorkhorse] leaving run(), removed 0 stale actions
  701. 28.10 13:56:14.033 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  702. 28.10 13:56:14.111 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 81 [ms]
  703. 28.10 13:56:14.117 INFO  [AeroflotBonusesServiceImpl] --- Start service ---
  704. 28.10 13:56:14.131 INFO  [AeroflotBonusesServiceImpl] updating settings!
  705. 28.10 13:56:14.132 TRACE [AeroflotBonusesServiceImpl] entering readLocalSettingsIntoObject()
  706. 28.10 13:56:14.136 TRACE [ActionsFilesReader] no new files; last id = 85909
  707. 28.10 13:56:14.137 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  708. 28.10 13:56:14.400 TRACE [AeroflotBonusesServiceImpl] leaving readLocalSettingsIntoObject(). the result is: AeroflotBonusesSettings{url='', timeout=30000, partnerId=0, certificatePassword='', minAmountDiscountMiles=1, location='', terminal='', certificatePath='modules/aeroflotBonusesCFT/CFT.pfx'}
  709. 28.10 13:56:14.442 TRACE [AeroflotBonusesServiceImpl] settings were reloaded. The result is: AeroflotBonusesSettings{url='http://127.0.0.1:50052/CFTAeroflotBonuses', timeout=10000000, partnerId=0, certificatePassword='', minAmountDiscountMiles=1, location='', terminal='', certificatePath='modules/aeroflotBonusesCFT/CFT.pfx'}
  710. 28.10 13:56:14.466 INFO  [AeroflotBonusesWsClient] Rebuild ws client url = 'http://127.0.0.1:50052/CFTAeroflotBonuses', certificatePath ='modules/aeroflotBonusesCFT/CFT.pfx', certificatePassword = '', timeout = '10000000'
  711. 28.10 13:56:14.471 ERROR [AeroflotBonusesWsClient] Unable to load certificate: file "modules/aeroflotBonusesCFT/CFT.pfx" is not found
  712. 28.10 13:56:20.318 DEBUG [TechProcessImpl] Server online mode
  713. 28.10 13:56:21.969 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  714. 28.10 13:56:21.993 TRACE [FileReader] getting new file..
  715. 28.10 13:56:22.002 TRACE [FileReader] No new file
  716. 28.10 13:56:22.065 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  717. 28.10 13:56:23.417 INFO  [AeroflotBonusesWsClient] Client successfully rebuild by url = 'http://127.0.0.1:50052/CFTAeroflotBonuses', timeout = '10000000'
  718. 28.10 13:56:23.471 INFO  [AeroflotBonusesServiceImpl] Loaded parameters = AeroflotBonusesSettings{url='http://127.0.0.1:50052/CFTAeroflotBonuses', timeout=10000000, partnerId=0, certificatePassword='', minAmountDiscountMiles=1, location='', terminal='', certificatePath='modules/aeroflotBonusesCFT/CFT.pfx'}
  719. 28.10 13:56:23.647 INFO  [SiebelServiceImpl] SiebelService with configuration: ru.crystals.siebel.SiebelServiceConfig@a22666[cardStatusConnectTimeout=15000,cardStatusRequestTimeout=15000,calculateConnectTimeout=15000,calculateRequestTimeout=15000,redeemConnectTimeout=3000,redeemRequestTimeout=3000,pendingOperationBatchSize=50,pendingOperationsRepeatInterval=60,pendingOperationsMaxRetries=0,wsdlUrl=file:/mnt/sda1/tce/storage/crystal-cash/modules/siebelBridge/wsdl/siebel-azbuka-testserver.wsdl,wsdlFile=modules/siebelBridge/wsdl/siebel-azbuka-testserver.wsdl,cardNumberLength=4,shopIndex=902,cashType=POS,discountNameMap={1=Фиксированная цена, 2=Скидка на ШК, 3=Скидка на товар, 4=Скидка на группу товаров, 5=Скидка на кол-во по товару, 6=Скидка на кол-во по группе, 7=Ручная скидка, 8=Скидка на группу продаж, 9=Скидка на кол-во по гр. продаж, 10=Скидка на товар по кат. ДК, 11=Скидка на сумму чека, 12=Скидка по ДК, 13=Скидка на группу по ДК, 14=Скидка на сумму по ДК, 15=Скидка на чек, 16=Скидка на кол-во по груп.ДК, 17=Скидка на гр.прод.по ДК, 18=Скидка на кол.по груп.пр.ДК, 19=Скидка на груп.по кат.ДК, 20=Скидка на вид оплат, 21=Скидка на набор, 22=Скидка по купону, 23=Скидка на сум.чека по кат.ДК, 24=Соц карта, 25=Скидка на отдел, 26=Скидка на кол-во чеков, 27=Скидка на ДР, 28=Скидка на товар в отд, 29=Скидка на округление, 30=Скидка по бонусам, 31=Скидка на сум.Груп.Тов, 32=Скидка на кат.клиента.ДК, 33=Скидка на кол-во,Груп.Продаж, 34=Скидка на потов.кол.ГП, 35=________1., 36=________2., 37=________3., 38=________4., 39=________5., 110=Марки, 112=Промо-код, 5005=Подарок},giftCardPrefixesString=<null>,discTypeFromActionAnyway=false]
  720. 28.10 13:56:23.654 DEBUG [SiebelServiceImpl] create ws-client..
  721. 28.10 13:56:23.709 DEBUG [SiebelServiceImpl] register SiebelService in BundleManager..
  722. 28.10 13:56:23.731 DEBUG [SiebelServiceImpl] Registered
  723. 28.10 13:56:24.138 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  724. 28.10 13:56:24.565 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 420 [ms]
  725. 28.10 13:56:24.630 TRACE [ActionsFilesReader] no new files; last id = 85909
  726. 28.10 13:56:24.635 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  727. 28.10 13:56:24.863 INFO  [ConfiguratorCashClient] Current version: 10.2.75.0, topologyAddress: 1.0.3388.1, cashType: POS
  728. 28.10 13:56:24.888 INFO  [ConfiguratorCashClient] Initialized topologyPoint: ConfigurationTopologyPoint {
  729.   type=POS,
  730.   topologyAddress=1.0.3388.1,
  731.   status=IN_WORK,
  732.   currentVersion=10.2.75.0,
  733.   previousVersion=,
  734.   planningVersion=,
  735.   topologyPointIP=172.29.17.13,
  736.   updateTime=null,
  737.   needMakeDbBackup=true,
  738.   needAutomaticRestart=null,
  739.   shiftMustBeClosed=false,
  740.   waitUpdateCommand=false,
  741.   localPatches=null,
  742.   online=false}
  743. 28.10 13:56:24.891 INFO  [ConfiguratorCashClient] Updates path /mnt/sda1/tce/storage/crystal-conf/updates
  744. 28.10 13:56:27.794 INFO  [CashConfigurationUpdateChecker] Waiting cdl...
  745. 28.10 13:56:27.821 INFO  [CashConfigurationUpdateChecker] sleepInt(60000)
  746. 28.10 13:56:28.009 INFO  [CommonLogger] POS loaded in 307 sec
  747. 28.10 13:56:32.116 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  748. 28.10 13:56:32.141 TRACE [FileReader] getting new file..
  749. 28.10 13:56:32.210 TRACE [FileReader] No new file
  750. 28.10 13:56:32.215 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  751. 28.10 13:56:34.636 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  752. 28.10 13:56:34.716 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 80 [ms]
  753. 28.10 13:56:34.834 TRACE [ActionsFilesReader] no new files; last id = 85909
  754. 28.10 13:56:34.837 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  755. 28.10 13:56:45.432 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  756. 28.10 13:56:45.432 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  757. 28.10 13:56:46.191 TRACE [FileReader] getting new file..
  758. 28.10 13:56:46.300 TRACE [FileReader] No new file
  759. 28.10 13:56:46.306 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  760. 28.10 13:56:46.406 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 1075 [ms]
  761. 28.10 13:56:46.440 TRACE [ActionsFilesReader] no new files; last id = 85909
  762. 28.10 13:56:46.442 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  763. 28.10 13:56:47.222 INFO  [CommonLogger] Time of starting visualization = 26 ms, totalMemory = 236781568, maxMemory = 259522560, freeMemory = 89668336
  764. 28.10 13:56:49.250 INFO  [DocumentSender] ping = true
  765. 28.10 13:56:49.480 INFO  [TransferManager] Message [userLogOut] has been sent
  766. 28.10 13:56:50.505 DEBUG [TechProcessImpl] Server online mode
  767. 28.10 13:56:52.245 INFO  [FiscalPrinter] getFactoryNum
  768. 28.10 13:56:52.249 INFO  [FiscalPrinter] FactoryNum = 0000033881
  769. 28.10 13:56:52.249 INFO  [FiscalPrinter] getRegNum
  770. 28.10 13:56:52.314 INFO  [FiscalPrinter] RegNum = NFM.3388.1.0.1571827454607
  771. 28.10 13:56:52.315 INFO  [FiscalPrinter] getEklzNum
  772. 28.10 13:56:52.315 INFO  [FiscalPrinter] EklzNum = 6de03059-181e-4147-b738-705af76216f2
  773. 28.10 13:56:52.316 INFO  [FiscalPrinter] getVerBios
  774. 28.10 13:56:52.316 INFO  [FiscalPrinter] VerBios = 27
  775. 28.10 13:56:53.748 TRACE [AeroflotBonusesServiceImpl] Not found pending operations
  776. 28.10 13:56:56.316 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  777. 28.10 13:56:56.376 TRACE [FileReader] getting new file..
  778. 28.10 13:56:56.433 TRACE [FileReader] No new file
  779. 28.10 13:56:56.433 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  780. 28.10 13:56:56.444 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  781. 28.10 13:56:57.240 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 796 [ms]
  782. 28.10 13:56:57.508 TRACE [ActionsFilesReader] no new files; last id = 85909
  783. 28.10 13:56:57.508 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  784. 28.10 13:56:57.505 TRACE [DocumentSender] entering getDocuments()
  785. 28.10 13:56:58.289 TRACE [DocumentSender] leaving getDocuments(). The result size is: 0; it took 791 [ms]
  786. 28.10 13:56:58.594 INFO  [TransferManager] OD found 0 documents to register
  787. 28.10 13:56:58.882 INFO  [DocumentSender] OD found 0 transactions to register
  788. 28.10 13:57:05.223 TRACE [HibernateBackedLoyTxDao] entering getLoyTxesByStatus(Collection, int). The arguments are: statuses [[NO_SENT, WAIT_ACKNOWLEDGEMENT, SENT_ERROR]], maxResults [100]
  789. 28.10 13:57:06.725 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  790. 28.10 13:57:06.730 TRACE [FileReader] getting new file..
  791. 28.10 13:57:06.823 TRACE [FileReader] No new file
  792. 28.10 13:57:06.827 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  793. 28.10 13:57:06.977 TRACE [HibernateBackedLoyTxDao] [0] loy-tx records were extracted from the db
  794. 28.10 13:57:06.982 TRACE [HibernateBackedLoyTxDao] leaving getLoyTxesByStatus(Collection, int). The result is: []; it took 1760 [ms]
  795. 28.10 13:57:07.509 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  796. 28.10 13:57:07.517 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 8 [ms]
  797. 28.10 13:57:07.545 TRACE [ActionsFilesReader] no new files; last id = 85909
  798. 28.10 13:57:07.545 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  799. 28.10 13:57:16.830 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  800. 28.10 13:57:16.843 TRACE [FileReader] getting new file..
  801. 28.10 13:57:16.869 TRACE [FileReader] No new file
  802. 28.10 13:57:16.870 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  803. 28.10 13:57:17.545 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  804. 28.10 13:57:17.587 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 42 [ms]
  805. 28.10 13:57:17.602 TRACE [ActionsFilesReader] no new files; last id = 85909
  806. 28.10 13:57:17.603 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  807. 28.10 13:57:20.509 DEBUG [TechProcessImpl] Server online mode
  808. 28.10 13:57:26.878 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  809. 28.10 13:57:26.884 TRACE [FileReader] getting new file..
  810. 28.10 13:57:26.894 TRACE [FileReader] No new file
  811. 28.10 13:57:26.894 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  812. 28.10 13:57:27.605 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  813. 28.10 13:57:27.609 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 4 [ms]
  814. 28.10 13:57:27.614 TRACE [ActionsFilesReader] no new files; last id = 85909
  815. 28.10 13:57:27.615 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
  816. 28.10 13:57:27.824 INFO  [CashConfigurationUpdateChecker] Current status: IN_WORK
  817. 28.10 13:57:27.826 INFO  [CashConfigurationUpdateChecker] Founded server ip: 172.29.17.29
  818. 28.10 13:57:27.827 INFO  [CashConfigurationUpdateChecker] Timeout: 20000
  819. 28.10 13:57:28.098 INFO  [CashConfigurationUpdateChecker] Send message:
  820. 28.10 13:57:28.192 INFO  [CashConfigurationUpdateChecker] Received patches list: []
  821. 28.10 13:57:28.225 INFO  [CashConfigurationUpdateChecker] isNeedWaitUpdateCommand: false
  822. 28.10 13:57:28.226 INFO  [CashConfigurationUpdateChecker] sleepInt(60000)
  823. 28.10 13:57:34.784 DEBUG [SetApiLoyaltyPluginBackgroundWorker] Queue returned null
  824. 28.10 13:57:34.786 DEBUG [SetApiLoyaltyPluginBackgroundWorker] Queue has no items, returning to polling.
  825. 28.10 13:57:34.786 DEBUG [SetApiLoyaltyPluginBackgroundWorker] Polling...
  826. 28.10 13:57:34.786 INFO  [PendingOperationQueue] Pending cards operation queue is empty. Populating from DB...
  827. 28.10 13:57:34.817 INFO  [PendingOperationQueue] No pending card operations found.
  828. 28.10 13:57:36.914 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
  829. 28.10 13:57:36.915 TRACE [FileReader] getting new file..
  830. 28.10 13:57:36.925 TRACE [FileReader] No new file
  831. 28.10 13:57:36.925 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
  832. 28.10 13:57:37.624 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
  833. 28.10 13:57:37.626 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 85909; sent-to-server: true; saved: true]; It took 2 [ms]
  834. 28.10 13:57:37.631 TRACE [ActionsFilesReader] no new files; last id = 85909
  835. 28.10 13:57:37.631 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
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!