Advertisement
Not a member of Pastebin yet?
Sign Up,
it unlocks many cool features!
- 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
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement