НЕ запускается УТМ после обновления

[nvariec]

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

Код: Выбрать все

2016-06-18 08:46:02,673 DEBUG ru.centerinform.terminal.agent.b.a - Команда: [sc start Transport-Monitoring] выполнилась с результатом: [1056]
2016-06-18 08:46:02,673 INFO  ru.centerinform.terminal.agent.b.a - StdInput: [[SC] StartService: ошибка: 1056:

Одна копия службы уже запущена.

]
2016-06-18 08:46:02,673 ERROR ru.centerinform.terminal.agent.b.a - ERROR: []
2016-06-18 08:46:02,720 DEBUG ru.centerinform.terminal.agent.b.a - Команда: [sc start Transport-Updater] выполнилась с результатом: [1056]
2016-06-18 08:46:02,736 INFO  ru.centerinform.terminal.agent.b.a - StdInput: [[SC] StartService: ошибка: 1056:

Одна копия службы уже запущена.

]
2016-06-18 08:46:02,736 ERROR ru.centerinform.terminal.agent.b.a - ERROR: []
2016-06-18 08:46:03,753 DEBUG ru.centerinform.terminal.agent.b.c - Ожидание:[3] сек.
2016-06-18 08:46:04,755 DEBUG ru.centerinform.terminal.agent.b.c - Ожидание:[2] сек.
2016-06-18 08:46:05,756 DEBUG ru.centerinform.terminal.agent.b.c - Ожидание:[1] сек.
2016-06-18 08:46:06,025 DEBUG ru.centerinform.terminal.agent.b.a - Команда: [sc start Transport] выполнилась с результатом: [0]
2016-06-18 08:46:06,041 INFO  ru.centerinform.terminal.agent.b.a - StdInput: [
Имя_службы: Transport
        Тип                : 10  WIN32_OWN_PROCESS 
        Состояние          : 2  START_PENDING
                                (NOT_STOPPABLE, NOT_PAUSABLE, IGNORES_SHUTDOWN)
        Код_выхода_Win32   : 0  (0x0)
        Код_выхода_службы  : 0  (0x0)
        Контрольная_точка  : 0x0
        Ожидание           : 0x7d0
        ID_процесса        : 4072
        Флаги              :
]

[nvariec]

Вопрос закрыт. Помогла инструкция:
viewtopic.php?f=2&t=3583

[ivanivan]

Аналогичная ошибка

Код: Выбрать все

2016-10-13 14:55:52,084 ERROR ru.centerinform.terminal.agent.d.a - ERROR: []
2016-10-13 14:55:53,098 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[15] сек.
2016-10-13 14:55:54,112 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[14] сек.
2016-10-13 14:55:55,126 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[13] сек.
2016-10-13 14:55:56,140 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[12] сек.
2016-10-13 14:55:57,154 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[11] сек.
2016-10-13 14:55:58,168 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[10] сек.
2016-10-13 14:55:59,182 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[9] сек.
2016-10-13 14:56:00,196 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[8] сек.
2016-10-13 14:56:01,210 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[7] сек.
2016-10-13 14:56:02,224 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[6] сек.
2016-10-13 14:56:03,238 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[5] сек.
2016-10-13 14:56:04,252 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[4] сек.
2016-10-13 14:56:05,266 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[3] сек.
2016-10-13 14:56:06,280 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[2] сек.
2016-10-13 14:56:07,294 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[1] сек.
2016-10-13 14:56:07,372 DEBUG ru.centerinform.terminal.agent.d.a - Команда: [sc start Transport] выполнилась с результатом: [0]
2016-10-13 14:56:07,372 INFO  ru.centerinform.terminal.agent.d.a - StdInput: [
Имя_службы: Transport
        Тип                : 10  WIN32_OWN_PROCESS 
        Состояние          : 2  START_PENDING
                                (NOT_STOPPABLE, NOT_PAUSABLE, IGNORES_SHUTDOWN)
        Код_выхода_Win32   : 0  (0x0)
        Код_выхода_службы  : 0  (0x0)
        Контрольная_точка  : 0x0
        Ожидание           : 0x7d0
        ID_процесса        : 4696
        Флаги              :
]
2016-10-13 14:57:21,490 DEBUG ru.centerinform.terminal.agent.d.a - Команда: [sc start Transport-Updater] выполнилась с результатом: [0]
2016-10-13 14:57:21,490 INFO  ru.centerinform.terminal.agent.d.a - StdInput: [
Имя_службы: Transport-Updater
        Тип                : 10  WIN32_OWN_PROCESS 
        Состояние          : 2  START_PENDING
                                (NOT_STOPPABLE, NOT_PAUSABLE, IGNORES_SHUTDOWN)
        Код_выхода_Win32   : 0  (0x0)
        Код_выхода_службы  : 0  (0x0)
        Контрольная_точка  : 0x0
        Ожидание           : 0x7d0
        ID_процесса        : 752
        Флаги              :
]
2016-10-13 14:57:22,492 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[15] сек.
2016-10-13 14:57:23,506 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[14] сек.
2016-10-13 14:57:24,520 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[13] сек.
2016-10-13 14:57:25,534 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[12] сек.
2016-10-13 14:57:26,548 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[11] сек.
2016-10-13 14:57:27,562 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[10] сек.
2016-10-13 14:57:28,576 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[9] сек.
2016-10-13 14:57:29,590 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[8] сек.
2016-10-13 14:57:30,604 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[7] сек.
2016-10-13 14:57:31,618 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[6] сек.
2016-10-13 14:57:32,632 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[5] сек.
2016-10-13 14:57:33,006 DEBUG ru.centerinform.terminal.agent.Agent - Запуск watchdog
2016-10-13 14:57:33,053 DEBUG ru.centerinform.terminal.agent.Agent - Транспорт не запущен
2016-10-13 14:57:33,053 DEBUG ru.centerinform.terminal.agent.Agent - При следующей проверке будет произведена попытка запустить транспорт
2016-10-13 14:57:33,646 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[4] сек.
2016-10-13 14:57:34,660 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[3] сек.
2016-10-13 14:57:35,674 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[2] сек.
2016-10-13 14:57:36,688 DEBUG ru.centerinform.terminal.agent.d.d - Ожидание:[1] сек.
2016-10-13 14:57:36,750 DEBUG ru.centerinform.terminal.agent.d.a - Команда: [sc start Transport] выполнилась с результатом: [0]
2016-10-13 14:57:36,750 INFO  ru.centerinform.terminal.agent.d.a - StdInput: [
Имя_службы: Transport
        Тип                : 10  WIN32_OWN_PROCESS 
        Состояние          : 2  START_PENDING
                                (NOT_STOPPABLE, NOT_PAUSABLE, IGNORES_SHUTDOWN)
        Код_выхода_Win32   : 0  (0x0)
        Код_выхода_службы  : 0  (0x0)
        Контрольная_точка  : 0x0
        Ожидание           : 0x7d0
        ID_процесса        : 5852
        Флаги              :
]

Перезаливка PKI и переустановка УТМ результата не дало. Что делать?

[pspbelru]

А что в логах transporter\l\transport_info.log и updater\l\update.log

[ivanivan]

transporter\l\transport_info.log

Код: Выбрать все

2016-10-13 15:30:54,456 INFO  es.programador.transport.Transport - Загрузка конфигурации
2016-10-13 15:30:54,534 INFO  es.programador.transport.a.a - Загрузка файла настроек: C:\UTM\transporter\conf\transport.properties
2016-10-13 15:30:54,550 INFO  es.programador.transport.a.a - Периодичность перезагрузки настроек выставлена в 10001 мс
2016-10-13 15:30:54,753 INFO  es.programador.transport.Transport - Чтение конфигурации
2016-10-13 15:30:54,924 INFO  es.programador.transport.l - Проверка состояния Transport Updater
2016-10-13 15:30:55,002 INFO  es.programador.transport.l - Версия Transport-Updater: 2.0.3b000155
2016-10-13 15:30:55,002 INFO  es.programador.transport.Transport - Чтение конфигурации успешно завершено
2016-10-13 15:30:55,018 INFO  es.programador.transport.Transport - TRANSPORT_VERSION: [2.0.3b000878 changeset:76845d6f34aa+]
2016-10-13 15:30:55,065 INFO  es.programador.transport.Transport - Конфигурация контекста для установки защищенного подключения к серверу
2016-10-13 15:30:55,065 INFO  es.programador.transport.d.b - Поиск номера слота смарт-карты (ЦентрИнформ)
2016-10-13 15:31:17,981 ERROR es.programador.transport.Transport - Ошибка проверки CRL
java.net.ConnectException: Connection timed out: connect
   at java.net.DualStackPlainSocketImpl.waitForConnect(Native Method)
   at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)
   at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)
   at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)
   at java.net.AbstractPlainSocketImpl.connect(Unknown Source)
   at java.net.PlainSocketImpl.connect(Unknown Source)
   at java.net.SocksSocketImpl.connect(Unknown Source)
   at java.net.Socket.connect(Unknown Source)
   at sun.net.NetworkClient.doConnect(Unknown Source)
   at sun.net.www.http.HttpClient.openServer(Unknown Source)
   at sun.net.www.http.HttpClient.openServer(Unknown Source)
   at sun.net.www.http.HttpClient.<init>(Unknown Source)
   at sun.net.www.http.HttpClient.New(Unknown Source)
   at sun.net.www.http.HttpClient.New(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.plainConnect(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.connect(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(Unknown Source)
   at sun.net.www.protocol.http.HttpURLConnection.getInputStream(Unknown Source)
   at java.net.URL.openStream(Unknown Source)
   at es.programador.transport.e.a(Unknown Source)
   at es.programador.transport.e.a(Unknown Source)
   at es.programador.transport.Transport.main(Unknown Source)
2016-10-13 15:31:17,997 INFO  es.programador.transport.Transport - FSRAR_ID: [020000332494]
2016-10-13 15:31:17,997 INFO  es.programador.transport.h - Открытие БД
2016-10-13 15:31:18,668 INFO  ru.centerinform.crypto.b - Инициализации криптобиблиотеки
2016-10-13 15:31:20,462 INFO  es.programador.transport.a.a - Загрузка файла настроек: C:\UTM\transporter\conf\transport.properties
2016-10-13 15:31:20,462 INFO  es.programador.transport.a.a - Периодичность перезагрузки настроек выставлена в 10001 мс
2016-10-13 15:31:21,086 INFO  es.programador.transport.Transport - Инициализация планировщика задач
2016-10-13 15:31:21,086 INFO  es.programador.transport.Transport - Отсылка данных по протоколу вер. 1 (http) false
2016-10-13 15:31:21,086 INFO  es.programador.transport.Transport - Отсылка данных по протоколу вер. 2 (веб-службы) true
2016-10-13 15:31:21,086 INFO  es.programador.transport.Transport - Использовать криптобиблиотеки true
2016-10-13 15:31:21,117 INFO  org.quartz.impl.StdSchedulerFactory - Using default implementation for ThreadExecutor
2016-10-13 15:31:21,132 INFO  org.quartz.core.SchedulerSignalerImpl - Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2016-10-13 15:31:21,132 INFO  org.quartz.core.QuartzScheduler - Quartz Scheduler v.2.1.6 created.
2016-10-13 15:31:21,132 INFO  org.quartz.simpl.RAMJobStore - RAMJobStore initialized.
2016-10-13 15:31:21,132 INFO  org.quartz.core.QuartzScheduler - Scheduler meta-data: Quartz Scheduler (v2.1.6) 'QuartzScheduler' with instanceId 'NON_CLUSTERED'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 1 threads.
  Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered.

2016-10-13 15:31:21,132 INFO  org.quartz.impl.StdSchedulerFactory - Quartz scheduler 'QuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2016-10-13 15:31:21,132 INFO  org.quartz.impl.StdSchedulerFactory - Quartz scheduler version: 2.1.6
2016-10-13 15:31:21,132 INFO  es.programador.transport.Transport - Запуск задачи обмена документами
2016-10-13 15:31:21,148 INFO  es.programador.transport.Transport - Период публикации и получения данных: 120 сек
2016-10-13 15:31:21,148 INFO  org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED started.
2016-10-13 15:31:21,148 INFO  es.programador.transport.l - Проверка состояния Transport Updater
2016-10-13 15:31:21,164 INFO  es.programador.transport.service.WebServer - Инициализация веб сервера
2016-10-13 15:31:21,179 INFO  org.eclipse.jetty.util.log - Logging initialized @27004ms
2016-10-13 15:31:21,195 INFO  es.programador.transport.l - Версия Transport-Updater: 2.0.3b000155
2016-10-13 15:31:21,210 INFO  es.programador.transport.g.c - Начало задачи обмена документами с сервером ЕГАИС по расписанию
2016-10-13 15:31:21,210 INFO  es.programador.transport.g.c - Отправка данных на сервер ЕГАИС по расписанию
2016-10-13 15:31:21,210 INFO  es.programador.transport.j - Отправка данных на сервер ЕГАИС
2016-10-13 15:31:21,210 INFO  es.programador.transport.service.WebServer - Файл C:\UTM\transporter\webapps\ROOT.war существует: true
2016-10-13 15:31:21,257 INFO  es.programador.transport.service.WebServer - Аутентификация отключена
2016-10-13 15:31:21,257 INFO  es.programador.transport.service.WebServer - Файл C:\UTM\transporter\webapps\opt.war существует: true
2016-10-13 15:31:21,257 INFO  es.programador.transport.f - Удаление исходящих документов в статусе: 1
2016-10-13 15:31:21,257 INFO  es.programador.transport.service.WebServer - Аутентификация отключена
2016-10-13 15:31:21,273 INFO  es.programador.transport.service.WebServer - Запуск веб сервера
2016-10-13 15:31:21,273 INFO  es.programador.transport.j - Завершение задачи отправки документов на сервер ЕГАИС
2016-10-13 15:31:21,273 INFO  es.programador.transport.g.c - Завершение задачи отправки документов на сервера ЕГАИС по расписанию
2016-10-13 15:31:21,273 INFO  es.programador.transport.g.c - Получение документов с сервера ЕГАИС по расписанию
2016-10-13 15:31:21,273 INFO  org.eclipse.jetty.server.Server - jetty-9.3.5.v20151012
2016-10-13 15:31:21,273 INFO  es.programador.transport.g.d - Получение документов с сервера ЕГАИС
2016-10-13 15:31:21,288 INFO  org.eclipse.jetty.server.AbstractNCSARequestLog - Opened C:\UTM\transporter\l\access.log.2016_10_13
2016-10-13 15:31:21,444 INFO  org.eclipse.jetty.webapp.StandardDescriptorProcessor - NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
2016-10-13 15:31:21,460 INFO  org.eclipse.jetty.server.handler.ContextHandler - Started o.e.j.w.WebAppContext@22a348{/,file:///C:/Windows/Temp/jetty-0.0.0.0-8080-ROOT.war-_-any-1873375219784973107.dir/webapp/,AVAILABLE}{/ROOT.war}
2016-10-13 15:31:21,491 INFO  org.eclipse.jetty.webapp.StandardDescriptorProcessor - NO JSP Support for /opt, did not find org.eclipse.jetty.jsp.JettyJspServlet
2016-10-13 15:31:21,491 INFO  org.eclipse.jetty.server.handler.ContextHandler - Started o.e.j.w.WebAppContext@1d69e82{/opt,file:///C:/Windows/Temp/jetty-0.0.0.0-8080-opt.war-_opt-any-7086702780063108279.dir/webapp/,AVAILABLE}{/opt.war}
2016-10-13 15:31:21,491 ERROR es.programador.transport.Transport - Ошибка инициализации и запуска транспорта
java.net.BindException: Address already in use: bind
   at sun.nio.ch.Net.bind0(Native Method)
   at sun.nio.ch.Net.bind(Unknown Source)
   at sun.nio.ch.Net.bind(Unknown Source)
   at sun.nio.ch.ServerSocketChannelImpl.bind(Unknown Source)
   at sun.nio.ch.ServerSocketAdaptor.bind(Unknown Source)
   at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:326)
   at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
   at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:244)
   at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
   at org.eclipse.jetty.server.Server.doStart(Server.java:384)
   at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
   at es.programador.transport.service.WebServer.b(Unknown Source)
   at es.programador.transport.Transport.a(Unknown Source)
   at es.programador.transport.Transport.main(Unknown Source)
2016-10-13 15:31:21,507 INFO  es.programador.transport.Transport - Завершение работы (ShutdownHook)
2016-10-13 15:31:21,507 INFO  es.programador.transport.Transport - Остановка служб
2016-10-13 15:31:21,507 INFO  es.programador.transport.service.WebServer - Остановка встроенного веб-сервера
2016-10-13 15:31:21,507 INFO  org.eclipse.jetty.server.ServerConnector - Stopped ServerConnector@1b77a8{HTTP/1.1,[http/1.1]}{0.0.0.0:8080}
2016-10-13 15:31:21,522 INFO  org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.w.WebAppContext@1d69e82{/opt,null,UNAVAILABLE}{/opt.war}
2016-10-13 15:31:21,538 INFO  org.eclipse.jetty.server.handler.ContextHandler - Stopped o.e.j.w.WebAppContext@22a348{/,null,UNAVAILABLE}{/ROOT.war}
2016-10-13 15:31:21,538 INFO  es.programador.transport.Transport - Остановка служб завершена
2016-10-13 15:31:21,538 INFO  es.programador.transport.Transport - Остановка работы планировщика задач
2016-10-13 15:31:21,538 INFO  org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED shutting down.
2016-10-13 15:31:21,538 INFO  org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED paused.
2016-10-13 15:31:21,538 INFO  org.quartz.core.QuartzScheduler - Scheduler QuartzScheduler_$_NON_CLUSTERED shutdown complete.
2016-10-13 15:31:21,538 INFO  ru.centerinform.crypto.b - Чистим ресурсы связанные с криптографером
2016-10-13 15:31:21,616 INFO  es.programador.transport.d.b - Начало вызова методов финализации
2016-10-13 15:31:21,616 INFO  es.programador.transport.d.b - Используется библиотека от ЦентрИнформ
2016-10-13 15:31:21,632 INFO  es.programador.transport.d.b - Завершение вызова методов финализации
2016-10-13 15:31:21,632 INFO  es.programador.transport.h - Закрытие БД
2016-10-13 15:31:21,632 INFO  es.programador.transport.util.b - Закрытие БД
2016-10-13 15:31:21,710 INFO  es.programador.transport.util.b - Закрытие базы успешно завершено с кодом: 08006


updater\l\update.log

Код: Выбрать все

2016-10-13 15:18:08,837 INFO  es.programador.transport.updater.h - Запуск системы обновления
2016-10-13 15:18:09,071 INFO  es.programador.transport.updater.h - Updater version: [2.0.3b000155] changeset: [92cc4529b72e+]
2016-10-13 15:18:09,087 DEBUG es.programador.transport.b.d - Битность системы: [32]
2016-10-13 15:18:09,087 DEBUG es.programador.transport.b.d - Версия системы: [Windows 7]
2016-10-13 15:18:09,087 DEBUG es.programador.transport.b.d - Windows: [true]
2016-10-13 15:18:09,087 DEBUG es.programador.transport.b.d - Linux: [false]
2016-10-13 15:18:09,103 DEBUG es.programador.transport.updater.h - Для RSA будет использоваться библиотека [C:\Windows\SysWOW64\jcPKCS11-2.dll]
2016-10-13 15:18:09,103 DEBUG es.programador.transport.b.d - Битность системы: [32]
2016-10-13 15:18:09,103 DEBUG es.programador.transport.b.d - Нативные файла находятся здесь:[C:\UTM\updater\lib\x32]
2016-10-13 15:18:09,118 DEBUG es.programador.transport.b.d - Создавать PKCS11 файл:always
2016-10-13 15:18:09,118 DEBUG es.programador.transport.b.d - Загрузка нативных библиотек из папки: [C:\UTM\updater\lib\x32]
2016-10-13 15:18:09,134 DEBUG es.programador.transport.b.b - Инициализация PKCS11. Начало
2016-10-13 15:18:09,134 DEBUG es.programador.transport.b.b - PKCS11 файл настройек: [C:\UTM\updater\lib\pkcs11.cfg]
2016-10-13 15:18:09,134 DEBUG es.programador.transport.b.b - PKCS11-библиотека:C:/Windows/SysWOW64/jcPKCS11-2.dll
2016-10-13 15:18:09,134 INFO  es.programador.transport.b.b - Поиск номера слота смарт-карты (ЦентрИнформ)
2016-10-13 15:18:09,929 DEBUG es.programador.transport.b.b - Конфигурационный файл: [name=TransportTerminal
library = "C:/Windows/SysWOW64/jcPKCS11-2.dll"
showInfo=true
slotListIndex=1]
2016-10-13 15:18:10,787 DEBUG es.programador.transport.b.b - Используется ключ: FSRAR-RSA-020000332494
2016-10-13 15:18:10,943 DEBUG es.programador.transport.b.b - Используется сертификат: [
[
  Version: V3
  Subject: EMAILADDRESS=E, CN=020000332494, OU=OU, O=O, L=L, ST=S, C=RU
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 1024 bits
  modulus: 154079900481296830647742330140216821052373895471702382571564369437043677773029545389316569659586835790943255442068246383163899140917701999139036490738494802814353829202946939563385443400989463000084442362697937156184897797162039362968193280077370412018681500002550122593155612873528623482498395558855176114451
  public exponent: 65537
  Validity: [From: Thu Oct 13 14:45:29 KRAT 2016,
               To: Fri Oct 13 14:55:29 KRAT 2017]
  Issuer: CN=pki.fsrar.ru, OU=IT, O=Russian Federal Service for Alcohol Market Regulation, L=Moskow, ST=Russian Federation, C=RU
  SerialNumber: [    3297f498 00000009 7e9e]

Certificate Extensions: 6
[1]: ObjectId: 1.3.6.1.5.5.7.1.1 Criticality=false
AuthorityInfoAccess [
  [
   accessMethod: caIssuers
   accessLocation: URIName: http://pki.fsrar.ru/ca/pki.crt
]
]

[2]: ObjectId: 2.5.29.35 Criticality=false
AuthorityKeyIdentifier [
KeyIdentifier [
0000: 07 7A 12 56 CE 3E 6F 5B   AB EF 3E 7A C4 9B 5A D9  .z.V.>o[..>z..Z.
0010: A6 AA 72 60                                        ..r`
]
]

[3]: ObjectId: 2.5.29.31 Criticality=false
CRLDistributionPoints [
  [DistributionPoint:
     [URIName: http://pki.fsrar.ru/ca/pki.crl]
]]

[4]: ObjectId: 2.5.29.37 Criticality=false
ExtendedKeyUsages [
  clientAuth
  1.2.643.3.6.78.4.49
]

[5]: ObjectId: 2.5.29.15 Criticality=true
KeyUsage [
  DigitalSignature
  Key_Encipherment
]

[6]: ObjectId: 2.5.29.14 Criticality=false
SubjectKeyIdentifier [
KeyIdentifier [
0000: 35 56 6C A0 C7 D9 0D A4   AE C7 4C 12 5D C2 54 28  5Vl.......L.].T(
0010: A3 37 27 79                                        .7'y
]
]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: A2 73 85 1B B4 0E 2C BB   C1 93 54 A4 7D C3 55 66  .s....,...T...Uf
0010: 6E D4 B9 8A 0F 37 66 73   D1 38 88 C6 DE 91 72 FF  n....7fs.8....r.
0020: B8 33 0D A2 35 E1 C2 C4   94 50 25 DE D9 15 0E 6B  .3..5....P%....k
0030: BA 1A 1D 4A CB 22 89 A5   F6 63 ED 9D 2A 7D 25 48  ...J."...c..*.%H
0040: 5A 8B 24 96 EE 81 54 E7   83 91 9D EE 56 50 07 56  Z.$...T.....VP.V
0050: 4E 37 76 0C B1 5A 19 9C   99 C4 95 51 6D AA C6 91  N7v..Z.....Qm...
0060: A6 D1 F7 14 76 A0 E5 13   27 02 20 E0 D3 28 7C F5  ....v...'. ..(..
0070: 1C 74 AD 60 28 77 E0 1B   DD 07 6C FD DE 7E 9A 8D  .t.`(w....l.....
0080: D0 89 3E 3B 20 96 3C 82   6C A8 66 AF F9 52 29 1C  ..>; .<.l.f..R).
0090: AE 9E A0 DD 87 B2 09 7A   63 C1 82 B6 AA 41 99 DC  .......zc....A..
00A0: 53 4D 27 EC F1 F7 3F 9F   AD 28 1B D3 07 3E 90 CF  SM'...?..(...>..
00B0: 4C 4D A7 15 96 F3 3C DD   F1 46 B9 F6 86 04 6E AD  LM....<..F....n.
00C0: 8B BB 0B 65 10 20 6F 97   EE 8B 18 94 B0 03 B6 AA  ...e. o.........
00D0: 1C 74 18 FD C5 A7 ED 79   C6 6A 6E AF 04 4D E6 17  .t.....y.jn..M..
00E0: DE 2A 28 59 BC 68 74 F7   A6 66 84 8F 25 0C 73 45  .*(Y.ht..f..%.sE
00F0: 2C E3 04 32 EF 5E 84 9C   AF F7 41 60 B7 49 BC CA  ,..2.^....A`.I..

]
2016-10-13 15:18:10,943 DEBUG es.programador.transport.b.e - Инициализация SSL. Начало
2016-10-13 15:18:10,943 DEBUG es.programador.transport.b.e - Инициализация SSLContext
2016-10-13 15:18:10,943 DEBUG es.programador.transport.b.e - Загрузка доверенных сертификатов
2016-10-13 15:18:10,959 DEBUG es.programador.transport.b.e - Используется настоящий TrustManager
2016-10-13 15:18:11,021 DEBUG es.programador.transport.b.e - Установка DefaultSSLSocketFactory
2016-10-13 15:18:11,021 DEBUG es.programador.transport.b.e - Инициализация SSL. Окончание
2016-10-13 15:18:11,021 DEBUG es.programador.transport.b.b - Инициализация закончена
2016-10-13 15:18:11,099 DEBUG es.programador.transport.updater.util.a - Сертификат сервера:[
[
  Version: V3
  Subject: EMAILADDRESS=admin@fsrar.ru, CN=update.fsrar.ru, OU=IT, O=Russian Federal Service for Alcohol Market Regulation, L=Moskow, ST=Russian Federation, C=RU
  Signature Algorithm: SHA1withRSA, OID = 1.2.840.113549.1.1.5

  Key:  Sun RSA public key, 2048 bits
  modulus: 26018656072389228155721129473207790247991604109293333229384347320108461241329532117033525056984229782982930698540707099333128817168123772477927616428527633719909447187184154011236412965720599683334628275918610652637376181349839844661803791215044146633191348920464724577425594290931794920843082993745063306076347366670959961365533263311564971420170390682489147790850826731092839282692034787735099337920402017895612343156551033674320308520030797720950202985336872973191169603514347037032498704249477777899238700064950422790689807843108529565052182947743427258612259559198034202770911290059618812745006355578052419264641
  public exponent: 65537
  Validity: [From: Mon Jun 30 21:20:01 KRAT 2014,
               To: Tue Jun 30 20:30:01 KRAT 2015]
  Issuer: CN=pki.fsrar.ru, OU=IT, O=Russian Federal Service for Alcohol Market Regulation, L=Moskow, ST=Russian Federation, C=RU
  SerialNumber: [    163f1865 00000000 0043]

Certificate Extensions: 7
[1]: ObjectId: 1.2.840.113549.1.9.15 Criticality=false
Extension unknown: DER encoded OCTET string =
0000: 04 37 30 35 30 0E 06 08   2A 86 48 86 F7 0D 03 02  .7050...*.H.....
0010: 02 02 00 80 30 0E 06 08   2A 86 48 86 F7 0D 03 04  ....0...*.H.....
0020: 02 02 00 80 30 07 06 05   2B 0E 03 02 07 30 0A 06  ....0...+....0..
0030: 08 2A 86 48 86 F7 0D 03   07                       .*.H.....


[2]: ObjectId: 1.3.6.1.5.5.7.1.1 Criticality=false
AuthorityInfoAccess [
  [
   accessMethod: caIssuers
   accessLocation: URIName: http://pki.fsrar.ru/ca/pki.crt
]
]

[3]: ObjectId: 2.5.29.35 Criticality=false
AuthorityKeyIdentifier [
KeyIdentifier [
0000: 07 7A 12 56 CE 3E 6F 5B   AB EF 3E 7A C4 9B 5A D9  .z.V.>o[..>z..Z.
0010: A6 AA 72 60                                        ..r`
]
]

[4]: ObjectId: 2.5.29.31 Criticality=false
CRLDistributionPoints [
  [DistributionPoint:
     [URIName: http://pki.fsrar.ru/ca/pki.crl]
]]

[5]: ObjectId: 2.5.29.37 Criticality=false
ExtendedKeyUsages [
  clientAuth
  1.2.643.3.6.78.4.49
]

[6]: ObjectId: 2.5.29.15 Criticality=true
KeyUsage [
  DigitalSignature
  Key_Encipherment
]

[7]: ObjectId: 2.5.29.14 Criticality=false
SubjectKeyIdentifier [
KeyIdentifier [
0000: 02 3A 7F 39 51 B9 6A C0   FE 0D E3 47 89 83 3C C2  .:.9Q.j....G..<.
0010: 26 79 6C 56                                        &ylV
]
]

]
  Algorithm: [SHA1withRSA]
  Signature:
0000: 3E 00 11 21 87 55 13 B7   24 3D 08 24 DE C2 4D 28  >..!.U..$=.$..M(
0010: 84 D4 9C AD C5 C7 44 19   1B 62 FF 63 91 64 E3 3E  ......D..b.c.d.>
0020: B3 26 15 94 10 7E A2 C7   E1 A6 72 20 98 80 23 9C  .&........r ..#.
0030: 92 DA 18 F0 19 FC 9B B5   D1 6D 3F 76 A7 CA 71 24  .........m?v..q$
0040: FB A1 C0 F4 D1 23 99 5D   8B DF EA 11 E1 C6 0F 80  .....#.]........
0050: E7 BF 1B 67 56 DF 20 A1   B4 22 3C 2B 87 8C C2 5D  ...gV. .."<+...]
0060: 4A 24 00 C4 1E BA AA E4   67 63 99 F4 7B 7D F0 E6  J$......gc......
0070: ED 30 A0 BC 25 9A A0 06   DD D1 47 33 76 B3 83 54  .0..%.....G3v..T
0080: A8 7C 8A 96 BE 3A CD C3   79 21 83 ED 8A 26 EC 02  .....:..y!...&..
0090: 58 A9 88 E8 DC 8E EA 3F   F8 45 72 3E 30 F9 7C 67  X......?.Er>0..g
00A0: C1 A9 F3 57 62 19 C4 E8   CE E2 3B B0 6B 12 B0 A3  ...Wb.....;.k...
00B0: 50 E6 84 1A 30 58 13 EE   2C 85 B8 F1 7D 07 22 AD  P...0X..,.....".
00C0: 47 06 C9 A4 0B 44 1F 4A   0D BC 80 70 1E CC EF 90  G....D.J...p....
00D0: A6 D8 F3 06 23 89 92 FC   68 84 3A 81 EF 5D AE A3  ....#...h.:..]..
00E0: 7E E0 71 64 90 E4 E9 AE   8C 92 20 E0 CA DF B7 59  ..qd...... ....Y
00F0: 59 88 7F 1B 32 A5 97 77   8D FF 21 31 B6 93 56 67  Y...2..w..!1..Vg

]
2016-10-13 15:18:11,099 DEBUG es.programador.transport.updater.h - Настройка таймаутов для соединения: connectTimeout=[30000 ms], readTimeout=[600000 ms]
2016-10-13 15:18:11,115 DEBUG es.programador.transport.updater.c.b - Запускаем служебный сервер на порту:8193
2016-10-13 15:18:11,177 DEBUG es.programador.transport.updater.c.d - Получение служебных запросов: 8193
2016-10-13 15:18:11,177 DEBUG es.programador.transport.updater.h - Служебный порт [8193] открыт: [OK]
2016-10-13 15:18:11,177 DEBUG es.programador.transport.updater.h - Запуск обновления в Thu Oct 13 15:18:11 KRAT 2016 с периодом: 86400000 мс
2016-10-13 15:18:11,177 INFO  es.programador.transport.updater.f - Запуск процедуры обновления
2016-10-13 15:18:11,193 DEBUG es.programador.transport.updater.h - Обновление по расписанию - попытка блокировать обновление.
2016-10-13 15:18:11,193 DEBUG es.programador.transport.updater.h - Обновление по расписанию - обновление блокировано.
2016-10-13 15:18:11,193 INFO  es.programador.transport.updater.h - Запуск задачи: h
2016-10-13 15:18:11,193 DEBUG es.programador.transport.updater.d.h - Транспорт уже установлен в директорию:[..\..\transporter]. Первичная инсталляция не требуется
2016-10-13 15:18:11,193 INFO  es.programador.transport.updater.h - Завершена задача: h
2016-10-13 15:18:11,193 INFO  es.programador.transport.updater.h - Запуск задачи: d
2016-10-13 15:18:11,193 DEBUG es.programador.transport.updater.d.d - Отправка запроса на получение текущей версии транспорта
2016-10-13 15:18:11,209 DEBUG es.programador.transport.updater.util.c - Отправка на 127.0.0.1:8192 команды [version]
2016-10-13 15:18:11,271 DEBUG es.programador.transport.updater.util.c - Ответ транспорта: 2.0.3b000878
2016-10-13 15:18:11,271 DEBUG es.programador.transport.updater.util.c - version: 2.0.3b000878
2016-10-13 15:18:11,271 DEBUG es.programador.transport.updater.d.d - Ответ транспорта: 2.0.3b000878
2016-10-13 15:18:11,271 DEBUG es.programador.transport.b.b - Используется ключ: FSRAR-RSA-020000332494
2016-10-13 15:18:11,427 DEBUG es.programador.transport.updater.util.d - Запрос списка обновления по URL:  https://update.egais.ru/update/check?v=2.0.3b000878&cid=777
2016-10-13 15:18:11,992 DEBUG es.programador.transport.updater.util.d - Ответ сервера:  [<?xml version="1.0" encoding="UTF-8"?><a/>
]
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.util.d - Обновление будет загружено по URL:  null
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.d.d - Обновление будет загружено по URL:  null
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.h - Завершена задача: d
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.h - Выполнение следующей команды не требуется.
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.h - Обновление завершено.
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.h - Обновление по расписанию - обновление разблокировано.
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.f - Завершение процедуры обновления
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.f - Запуск процедуры обновления системных настроек
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.h - Обновление настроек по расписанию - попытка блокировать обновление.
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.h - Обновление настроек по расписанию - обновление блокировано.
2016-10-13 15:18:12,023 INFO  es.programador.transport.updater.h - Запуск задачи: b
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.d.b - Получение hash файла настроек
2016-10-13 15:18:12,023 DEBUG es.programador.transport.updater.d.b - Hash системных настроек: 068336965585fb8857ebf4d3798407a5
2016-10-13 15:18:12,023 DEBUG es.programador.transport.b.b - Используется ключ: FSRAR-RSA-020000332494
2016-10-13 15:18:12,179 DEBUG es.programador.transport.updater.util.d - Запрос системных настроек по URL:  https://update.egais.ru/properties/check?h=068336965585fb8857ebf4d3798407a5
2016-10-13 15:18:12,257 DEBUG es.programador.transport.updater.util.d - Ответ сервера:  [<?xml version="1.0" encoding="UTF-8"?>
<a/>
]
2016-10-13 15:18:12,257 DEBUG es.programador.transport.updater.util.d - Настройки будут загружены по URL:  null
2016-10-13 15:18:12,257 DEBUG es.programador.transport.updater.d.b - Системные настройки будут загружены по URL:  null
2016-10-13 15:18:12,257 INFO  es.programador.transport.updater.h - Завершена задача: b
2016-10-13 15:18:12,257 INFO  es.programador.transport.updater.h - Выполнение следующей команды не требуется.
2016-10-13 15:18:12,257 INFO  es.programador.transport.updater.h - Обновление системных настроек завершено.
2016-10-13 15:18:12,257 DEBUG es.programador.transport.updater.h - Обновление настроек по расписанию - обновление разблокировано.
2016-10-13 15:18:12,257 INFO  es.programador.transport.updater.f - Завершение процедуры обновления системных настроек
2016-10-13 15:18:51,697 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:19:37,292 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: isdownloaded
2016-10-13 15:22:32,094 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:22:58,287 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:24:53,450 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:25:19,596 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:30:55,002 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version
2016-10-13 15:31:21,195 DEBUG es.programador.transport.updater.c.a - Получена служебная комманда: version

Может сами файлы приложить?

[pspbelru]

В логе транспорта есть "Address already in use". Порт 8080 ничем другим не занят? Может видеонаблюдение есть на этом компе? Попробуйте другой порт настроить.

[ivanivan]

Да, что-то висит на этом порту. Изменил порт - все заработало. Спасибо за помощь!

[Vintik]

У меня была ошибка (после других исправлений с драйверами ключей),
в логе agent.log
...
2020-02-18 13:53:22,022 INFO ru.centerinform.terminal.agent.d.a - StdInput: [[SC] StartService: ошибка: 1056:
Одна копия службы уже запущена.
...

На что обращал внимание и не мог понять, что надо, уже всё переставил...
В и тоге понял это такой ПК и видно при первой запуске (думал) и эту ошибку ложную делал,
но а далее оказывается не работал л.к. в файле "transport_info.log" нашёл виновника

2020-02-18 11:53:42,510 WARN es.programador.transport.Transport - FSRAR_ID (CN RSA сертификата) на используемом ключе не соответствует FSRAR_ID первичной инициализации УТМ.

В итоге удалил transportDB и всё пошло.
Логи это всё же хорошая штука там.