IPB

Здравствуйте, гость ( Вход | Регистрация )

 
Добавить ответ в эту темуОткрыть тему
Hub-19 и Jacarta-2 = постоянные проблемы
Rafael
сообщение 8.8.2019, 12:00
Сообщение #1


Постоялец
*****

Группа: Пользователи
Сообщений: 897
Регистрация: 23.9.2007
Пользователь №: 11 806



Добрый день.
Более 100 хабов HUB-16 работали на первых Jacarta, описанной ниже проблемы не было. Перевели около 50 хабов на Jacarta2 - начались проблемы
Магазины торгует алкоголем нон-стоп, в момент закрытия чека с алкоголем во Фронтоле 6.3 УТМ загорается красным и следующий чек закрыть нельзя.
Может 5 чеков пропустить и упасть на 6-м, рандомно.
Это происходит на всех 50 хабах с Jacarta-2, нам это четко видно. Ради теста пробовали некоторые хабы с нуля прошить последней доступной SD, сразу же обновить УТМ 3.0.8 с флешки и догнать все "воздушные" обновления хабов - не помогло - продолжают падать.
Падает при попытке подписания документа, тушит службы, перезапускает УТМ и какое-то время подписывает нормально.
Прошу пояснить, что с этим делать, непрерывные скандалы на кассах уже утомили.

Ниже кусок лога для понимания:


2019-08-08 09:38:45,734 INFO es.programador.http.AbstractServlet - Получен чек
2019-08-08 09:38:47,371 ERROR ru.centerinform.crypto.c - Ошибка подписи документа
java.lang.IllegalStateException: sun.security.pkcs11.wrapper.PKCS11Exception: CKR_USER_NOT_LOGGED_IN
at ru.centerinform.crypto.m.b(Unknown Source)
at ru.centerinform.crypto.n.a(Unknown Source)
at ru.centerinform.crypto.j.b(Unknown Source)
at ru.centerinform.crypto.j.a(Unknown Source)
at ru.centerinform.crypto.c.a(Unknown Source)
at ru.centerinform.crypto.c.a(Unknown Source)
at es.programador.http.AbstractServlet.a(Unknown Source)
at es.programador.http.AbstractServlet.a(Unknown Source)
at es.programador.http.AbstractServlet.doPost(Unknown Source)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:583)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.RequestLogHandler.handle(RequestLogHandler.java:56)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.jav
a:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
Caused by: sun.security.pkcs11.wrapper.PKCS11Exception: CKR_USER_NOT_LOGGED_IN
at sun.security.pkcs11.wrapper.PKCS11.C_SignFinal(Native Method)
at sun.security.pkcs11.wrapper.PKCS11$SynchronizedPKCS11.C_SignFinal(PKCS11.java:1736)
... 35 more
2019-08-08 09:38:47,806 INFO ru.centerinform.crypto.c - Вторая попытка подписать документ
2019-08-08 09:38:47,807 INFO ru.centerinform.crypto.c - Инициализации криптобиблиотеки
2019-08-08 09:38:48,267 INFO es.programador.http.AbstractServlet - [Future] Результат проверки чека: [Проверка пройдена]
2019-08-08 09:38:48,452 ERROR ru.centerinform.crypto.c - Не удалось полученить сертификат, слот: eToken GOST
java.lang.IllegalStateException: Список сертификатов пуст
at ru.centerinform.crypto.c.b(Unknown Source)
at ru.centerinform.crypto.c.k(Unknown Source)
at ru.centerinform.crypto.c.i(Unknown Source)
at ru.centerinform.crypto.c.a(Unknown Source)
at ru.centerinform.crypto.c.a(Unknown Source)
at ru.centerinform.crypto.c.a(Unknown Source)
at es.programador.http.AbstractServlet.a(Unknown Source)
at es.programador.http.AbstractServlet.a(Unknown Source)
at es.programador.http.AbstractServlet.doPost(Unknown Source)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:707)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:821)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:583)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213)
at org.eclipse.jetty.server.handler.RequestLogHandler.handle(RequestLogHandler.java:56)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
at org.eclipse.jetty.server.Server.handle(Server.java:517)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:306)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.jav
a:213)
at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
at java.lang.Thread.run(Thread.java:745)
2019-08-08 09:38:58,432 INFO ru.centerinform.crypto.c - Используется сертификат SerialNumber: 145e961009daa7da8429c213d6361c258
2019-08-08 09:38:59,947 INFO es.programador.http.AbstractServlet - Сохранение данных для последующей отправки: Ticket{inn=5505043485, kpp=550645001, address=644023 Омск 3 Железнодорожная 22 а, cashboxId=9280440300046823, sequenceId=114, ticketId=20868, rawDate=0808190938, products=[Bottle {barcode=136401943486091118001VX5S2E74564RGM6JVPUM5BGCHE3QFNK6WZ2L6TTCXQYXIICW7652NWUNZG36LEE67W4WLR
YVYJA6I7BJ2777SZC37TDBBO5NWFAM6BM3OHPEBVZNUVTHQTM4HHT43FFLY, price=242.00, ean=, volume=0.5000}]}
2019-08-08 09:39:31,299 INFO es.programador.transport.Transport - Завершение работы (ShutdownHook)
2019-08-08 09:39:31,323 INFO es.programador.transport.Transport - Остановка служб
2019-08-08 09:39:31,393 INFO org.eclipse.jetty.server.ServerConnector - Stopped ServerConnector@48b37a{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
2019-08-08 09:39:31,425 INFO org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.w.WebAppContext@165539b{/opt,null,UNAVAILABLE}{/opt.war}
2019-08-08 09:39:31,469 INFO es.programador.http.AbstractServlet - Завершение работы сервлета:XmlServlet
2019-08-08 09:39:31,471 INFO es.programador.http.AbstractServlet - Закрытие криптобиблиотеки
2019-08-08 09:39:31,479 INFO ru.centerinform.crypto.c - Чистим ресурсы связанные с криптографером
2019-08-08 09:39:31,793 INFO org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.w.WebAppContext@cfcbc9{/,null,UNAVAILABLE}{/ROOT.war}
2019-08-08 09:39:31,848 INFO es.programador.transport.service.WebServer - Остановка встроенного веб-сервера
2019-08-08 09:39:31,852 INFO es.programador.transport.Transport - Остановка служб завершена
2019-08-08 09:39:31,861 INFO es.programador.transport.Transport - Остановка таймера сохранения фильтра
2019-08-08 09:39:31,870 INFO es.programador.transport.Transport - Остановка работы планировщика задач
2019-08-08 09:39:31,873 INFO org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED shutting down.
2019-08-08 09:39:31,874 INFO org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED paused.
2019-08-08 09:39:31,877 INFO org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED shutdown complete.
2019-08-08 09:39:31,882 INFO ru.centerinform.crypto.c - Чистим ресурсы связанные с криптографером
2019-08-08 09:39:31,883 INFO ru.centerinform.crypto.c - Чистка ресурсов не требуется, криптографер не был проинициализирован
2019-08-08 09:39:31,902 INFO es.programador.transport.j - Закрытие БД
2019-08-08 09:39:31,931 INFO es.programador.transport.util.b - Закрытие БД
2019-08-08 09:39:33,192 INFO es.programador.transport.util.b - Закрытие базы успешно завершено с кодом: 08006
2019-08-08 09:39:45,809 INFO es.programador.transport.Transport - Загрузка конфигурации
2019-08-08 09:39:48,778 INFO es.programador.transport.b.a - Загрузка файла настроек: /opt/utm/transport/conf/transport.properties
2019-08-08 09:39:49,099 INFO es.programador.transport.b.a - Периодичность перезагрузки настроек выставлена в 10001 мс
2019-08-08 09:39:53,663 INFO es.programador.transport.Transport - Чтение конфигурации
2019-08-08 09:39:58,479 INFO es.programador.transport.q - Проверка состояния Transport Updater
2019-08-08 09:39:59,010 ERROR es.programador.transport.q - Ошибка проверки состояния Transport Updater.
java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
at java.net.Socket.connect(Socket.java:589)
at sun.security.ssl.SSLSocketImpl.connect(SSLSocketImpl.java:668)
at es.programador.transport.util.h.a(Unknown Source)
at es.programador.transport.util.g.a(Unknown Source)
at es.programador.transport.util.g.a(Unknown Source)
at es.programador.transport.q.b(Unknown Source)
at es.programador.transport.q.a(Unknown Source)
at es.programador.transport.Transport.main(Unknown Source)
2019-08-08 09:39:59,149 INFO es.programador.transport.q - Проверьте запущен ли Transport Updater.
2019-08-08 09:39:59,161 INFO es.programador.transport.q - Проверьте не занят ли порт [8193] другим приложением
2019-08-08 09:39:59,178 INFO es.programador.transport.Transport - Повторная проверка начнется через [60] сек.
2019-08-08 09:40:59,211 INFO es.programador.transport.q - Проверка состояния Transport Updater
2019-08-08 09:40:59,863 INFO es.programador.transport.q - Версия Transport-Updater: [2.1.7b000345]
2019-08-08 09:41:00,049 INFO es.programador.transport.Transport - Чтение конфигурации успешно завершено
2019-08-08 09:41:00,085 INFO es.programador.transport.Transport - TRANSPORT_VERSION: [3.0.8b001243 changeset:2e1f81b3762c]
2019-08-08 09:41:00,104 INFO es.programador.transport.Transport - JAVA_VERSION: [1.8.0_65]
2019-08-08 09:41:00,105 INFO es.programador.transport.Transport - JAVA_VENDOR: [Oracle Corporation]
2019-08-08 09:41:00,107 INFO es.programador.transport.Transport - JAVA_VM_NAME: [Java HotSpot™ Client VM]
2019-08-08 09:41:00,111 INFO es.programador.transport.Transport - JAVA 32 or 64: [32]
2019-08-08 09:41:00,113 INFO es.programador.transport.Transport - OS_NAME: [Linux]
2019-08-08 09:41:00,114 INFO es.programador.transport.Transport - OS_ARCH: [arm]
2019-08-08 09:41:00,115 INFO es.programador.transport.Transport - OS_VERSION: [3.4.39]
2019-08-08 09:41:00,439 INFO es.programador.transport.e.c - Linux - Копирование библиотек отключено
2019-08-08 09:41:00,441 INFO es.programador.transport.e.c - Linux - Копирование библиотек отключено
2019-08-08 09:41:00,442 INFO es.programador.transport.e.c - Linux - Копирование библиотек отключено
2019-08-08 09:41:00,443 INFO es.programador.transport.e.c - Linux - Копирование библиотек отключено
2019-08-08 09:41:00,662 INFO es.programador.transport.Transport - Конфигурация контекста для установки защищенного подключения к серверу
2019-08-08 09:41:03,772 INFO es.programador.transport.e.b - Найден слот смарт-карты [131071:eToken GOST ]
2019-08-08 09:41:03,926 INFO es.programador.transport.e.b - Найден слот смарт-карты [196607:JaCarta GOST 2.0]
2019-08-08 09:41:04,176 INFO es.programador.transport.e.b - Найден слот смарт-карты [262143:JaCarta Laser ]
2019-08-08 09:41:07,344 INFO es.programador.transport.e.b - Используется сертификат SerialNumber: 2889386b0000000c0cac
2019-08-08 09:41:07,441 INFO es.programador.transport.e.b - RSA сертификат: [
[

----------

Вот тут http://forum.atol.ru/index.php?showtopic=3...st&p=283183 человек пишет о том же
Пока не бьют чеки, УТМ не падает.
Вернуться в начало страницы
 
+Ответить с цитированием данного сообщения
Rafael
сообщение 13.8.2019, 13:35
Сообщение #2


Постоялец
*****

Группа: Пользователи
Сообщений: 897
Регистрация: 23.9.2007
Пользователь №: 11 806



Для тех, кто столкнется и будет искать поиском - это вредит Супервизор УТМ в хабе
Инженер техподержки Атола нашел.
Цитирую по памяти:
Ранее УТМ не мог сам восстанавливаться и сделали Супервизор УТМ в хабе, который по определенным ошибкам перезапускал УТМ
В данном конкретном случае Супервизор считает, что нужно опрокинуть УТМ в хабе, если встретилась ошибка подписания документа. Если в хабе выключить этот Супервизор УТМ, УТМ делает еще одну попытку подписания и подписывает успешно.
Текущее решение проблемы - только массово выключить Супервизор УТМ в хабах (мы так сделали у себя)
Исключение этой ошибки из причины для ребута УТМ Супервизором обещают к концу августа этого года.

Вторая часть проблемы (периодическая ошибка подписания с первой попытки) не решена. Стоит заменить 2-ю Jacarta на первую, проблема пропадает.
Возможно, это связано с опросом в первую очередь не того контейнера (во второй Jacarta их 2 для ГОСТа), таймаутами при этом, но это мои домыслы.
Вернуться в начало страницы
 
+Ответить с цитированием данного сообщения

Добавить ответ в эту темуОткрыть тему
1 чел. читают эту тему (гостей: 1, скрытых пользователей: 0)
Пользователей: 0

 



RSS Текстовая версия Сейчас: 17.8.2019, 19:33
 Яндекс цитирования  Rambler's Top100