Pasted

17.11 11:22:23.554 ERROR [ServiceImpl] Answer file not found!
17.11 11:22:23.835 INFO  [CommonLogger] Starting cleaning. All log records older than 19 августа 2019 11:22:23 will be deleted.
17.11 11:22:27.390 INFO  [CommonLogger] Cleaning done.
17.11 11:22:27.051 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
17.11 11:22:27.449 TRACE [FileReader] getting new file..
17.11 11:22:27.548 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
17.11 11:22:27.583 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 89026; sent-to-server: true; saved: true]; It took 35 [ms]
17.11 11:22:27.861 TRACE [ActionsFilesReader] no new files; last id = 89026
17.11 11:22:27.861 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
17.11 11:22:27.873 TRACE [FileReader] No new file
17.11 11:22:27.873 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
17.11 11:22:28.804 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 113
17.11 11:22:28.975 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 113
17.11 11:22:28.975 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 113
17.11 11:22:28.996 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=113]]
17.11 11:22:30.006 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 27
17.11 11:22:30.007 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 27
17.11 11:22:30.007 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 27
17.11 11:22:30.027 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=27]]
17.11 11:22:30.207 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 113
17.11 11:22:30.208 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 113
17.11 11:22:30.208 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 113
17.11 11:22:30.228 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=113]]
17.11 11:22:30.408 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 112
17.11 11:22:30.408 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 112
17.11 11:22:30.408 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 112
17.11 11:22:30.428 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=112]]
17.11 11:22:37.873 DEBUG [FileReader] FileTransportReader run "OPERDAY_TO_CASH"
17.11 11:22:39.445 TRACE [FileReader] getting new file..
17.11 11:22:37.861 TRACE [HibernateBackedActionsTransportAuxiliariesDao] entering getLastDiscountId()
17.11 11:22:39.456 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 113
17.11 11:22:39.456 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 113
17.11 11:22:39.457 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 113
17.11 11:22:39.459 TRACE [HibernateBackedActionsTransportAuxiliariesDao] leaving getLastDiscountId(). The result is: last-discount-id [disc-id: 89026; sent-to-server: true; saved: true]; It took 1598 [ms]
17.11 11:22:39.450 DEBUG [TechProcessImpl] Server online mode
17.11 11:22:42.114 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=113]]
17.11 11:22:42.115 TRACE [FileReader] No new file
17.11 11:22:42.115 DEBUG [FileReader] Scheduling next call of data type "OPERDAY_TO_CASH" after 10 seconds.
17.11 11:22:43.283 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 27
17.11 11:22:43.284 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 27
17.11 11:22:43.284 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 27
17.11 11:22:43.326 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=27]]
17.11 11:22:43.326 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 113
17.11 11:22:43.327 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 113
17.11 11:22:43.327 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 113
17.11 11:22:43.347 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=113]]
17.11 11:22:43.479 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 114
17.11 11:22:43.479 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 114
17.11 11:22:43.479 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 114
17.11 11:22:43.499 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=114]]
17.11 11:22:44.051 DEBUG [KeyboardImpl] -> KEY PRESSED: keyCode = 113
17.11 11:22:44.052 DEBUG [KeyboardImpl] -> KEY RELEASED: keyCode = 113
17.11 11:22:44.052 DEBUG [KeyboardImpl] ---> KEY RELEASED !!!: keyCode = 113
17.11 11:22:44.072 DEBUG [KeyboardImpl] keyboard - keysqueue [[Key scanCode=113]]
17.11 11:22:43.335 TRACE [ActionsFilesReader] no new files; last id = 89026
17.11 11:22:44.781 DEBUG [ActionsFilesReader] Scheduling next call of data type "LOY" after 10 seconds.
17.11 11:22:44.749 ERROR [TransactionHandler] null
java.lang.RuntimeException: java.sql.SQLTransientConnectionException: HikariPool-3 - Connection is not available, request timed out after 8233ms.
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:184) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.TransactionHandler.invoke(TransactionHandler.java:25) [JDBCMapper.jar:10.2.75.0]
    at com.sun.proxy.$Proxy174.getProperty(Unknown Source) [?:?]
    at ru.crystals.pos.esb.KafkaProducerBeanImpl.isEnabled(KafkaProducerBeanImpl.java:65) [?:?]
    at ru.crystals.pos.check.service.transport.TransferManager.sendByESBEnabled(TransferManager.java:1001) [?:10.2.75.1]
    at ru.crystals.pos.check.service.transport.DocumentSender.sendObject(DocumentSender.java:309) [document.jar:10.2.75.1]
    at ru.crystals.pos.check.service.transport.TransferManager$CashStatusSender.run(TransferManager.java:307) [document.jar:10.2.75.1]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_112]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:1.8.0_112]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_112]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:1.8.0_112]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
    at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
Caused by: java.sql.SQLTransientConnectionException: HikariPool-3 - Connection is not available, request timed out after 8233ms.
    at com.zaxxer.hikari.pool.HikariPool.createTimeoutException(HikariPool.java:676) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:190) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:155) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.HikariDataSource.getConnection(HikariDataSource.java:100) ~[HikariCP-3.2.0.jar:?]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperDSImpl.getConnection(JDBCMapperDSImpl.java:53) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:178) ~[JDBCMapper.jar:10.2.75.0]
    ... 13 more
Caused by: org.postgresql.util.PSQLException: Соединение уже было закрыто
    at org.postgresql.jdbc.PgConnection.checkClosed(PgConnection.java:767) ~[postgresql-42.2.2.jar:42.2.2]
    at org.postgresql.jdbc.PgConnection.setNetworkTimeout(PgConnection.java:1537) ~[postgresql-42.2.2.jar:42.2.2]
    at com.zaxxer.hikari.pool.PoolBase.setNetworkTimeout(PoolBase.java:550) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.PoolBase.isConnectionAlive(PoolBase.java:165) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:179) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:155) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.HikariDataSource.getConnection(HikariDataSource.java:100) ~[HikariCP-3.2.0.jar:?]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperDSImpl.getConnection(JDBCMapperDSImpl.java:53) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:178) ~[JDBCMapper.jar:10.2.75.0]
    ... 13 more
17.11 11:22:49.075 WARN  [TransferManager] java.sql.SQLTransientConnectionException: HikariPool-3 - Connection is not available, request timed out after 8233ms.
java.lang.RuntimeException: java.sql.SQLTransientConnectionException: HikariPool-3 - Connection is not available, request timed out after 8233ms.
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:184) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.TransactionHandler.invoke(TransactionHandler.java:25) ~[JDBCMapper.jar:10.2.75.0]
    at com.sun.proxy.$Proxy174.getProperty(Unknown Source) ~[?:?]
    at ru.crystals.pos.esb.KafkaProducerBeanImpl.isEnabled(KafkaProducerBeanImpl.java:65) ~[?:?]
    at ru.crystals.pos.check.service.transport.TransferManager.sendByESBEnabled(TransferManager.java:1001) ~[?:10.2.75.1]
    at ru.crystals.pos.check.service.transport.DocumentSender.sendObject(DocumentSender.java:309) ~[document.jar:10.2.75.1]
    at ru.crystals.pos.check.service.transport.TransferManager$CashStatusSender.run(TransferManager.java:307) [document.jar:10.2.75.1]
    at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_112]
    at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:1.8.0_112]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_112]
    at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:1.8.0_112]
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112]
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112]
    at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112]
Caused by: java.sql.SQLTransientConnectionException: HikariPool-3 - Connection is not available, request timed out after 8233ms.
    at com.zaxxer.hikari.pool.HikariPool.createTimeoutException(HikariPool.java:676) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:190) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:155) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.HikariDataSource.getConnection(HikariDataSource.java:100) ~[HikariCP-3.2.0.jar:?]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperDSImpl.getConnection(JDBCMapperDSImpl.java:53) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:178) ~[JDBCMapper.jar:10.2.75.0]
    ... 13 more
Caused by: org.postgresql.util.PSQLException: Соединение уже было закрыто
    at org.postgresql.jdbc.PgConnection.checkClosed(PgConnection.java:767) ~[postgresql-42.2.2.jar:42.2.2]
    at org.postgresql.jdbc.PgConnection.setNetworkTimeout(PgConnection.java:1537) ~[postgresql-42.2.2.jar:42.2.2]
    at com.zaxxer.hikari.pool.PoolBase.setNetworkTimeout(PoolBase.java:550) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.PoolBase.isConnectionAlive(PoolBase.java:165) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:179) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.pool.HikariPool.getConnection(HikariPool.java:155) ~[HikariCP-3.2.0.jar:?]
    at com.zaxxer.hikari.HikariDataSource.getConnection(HikariDataSource.java:100) ~[HikariCP-3.2.0.jar:?]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperDSImpl.getConnection(JDBCMapperDSImpl.java:53) ~[JDBCMapper.jar:10.2.75.0]
    at ru.crystals.pos.datasource.jdbc.JDBCMapperImpl.startTransaction(JDBCMapperImpl.java:178) ~[JDBCMapper.jar:10.2.75.0]
    ... 13 more
17.11 11:22:48.107 INFO  [CashConfigurationUpdateChecker] Current status: IN_WORK
17.11 11:22:51.652 INFO  [CashConfigurationUpdateChecker] Received patches list: []
17.11 11:22:49.123 INFO  [MLServiceImpl] Number of pending operations (DISSOCIATING_CARD_MANZANA): 3