LERS GSM Plus не соединяется с ЛЭРС

Модем не соединяется с ЛЭРС в лог пишет Ошибка идентификации.
Номер модема в идентификаторе выставлен правильно.
Лог при подключении, снято с телнет:
#P7ZR-6E85
CR#MANUAL
DI#DIS
FV#R7.52.0
SV#P3.4.18
HV#??? GSM Plus 4.X.X
IMEI#351865060352649
MO#MTS-RUS,25001
GSM# 1.6605,9052,-90dBm 36%,Y,900; 2.6605,763,-103dBm 15%,Y,900; 3.6605,5951,-103dBm 15%,Y,900; Rating: Poor
PORT#0:PORT1,1,9600,8,N,1,0,0; 0:PORT2,1,9600,8,N,1,0,0
DEFP#COM1
LOC#La:57.7169800,Lo:60.1165390,Pr:1600
SIM#ID:89701016426303224402,CONTROL:DISABLED
GROUP#
BAL#“DISABLED”
DT#2017-07-26 14-56-08,+6
CONNECT GPRS

Что-то меня беспокоит HV#???
Боюсь как бы не рецидив http://forum.lers.ru/viewtopic.php?f=6&t=5065 , хотя к заводским настройкам не скинулся.

Сейчас посмотрел по логам Ошибка идентификации происходит на этом сервере практически со всеми модемами, началось в прошлом месяце. Есть второй сервер там так же стоят LERS GSM Plus но такой ошибки не возникает. Модемы стоят в одних и тех же районах городах качество связи на них одинаковое. Тем более что ручной командой на подключение к моему рабочему ПК подключаются нормально. По 2-3 суток происходит проблема, потом соединяется нормально, несколько часов/суток работает нормально - снова проблемы.
Заменяю в настройках модем на стандартный - все равно Ошибка идентификации, там же нет контрольного номера для идентификации, это вообще ни в какие ворота не лезет!
Два модема на этом сервере LERS GSM Lite не имеют такой проблемы.

Пришлите журнал работы Службы опроса, в котором зафиксирована ошибка идентификации.

Также отправьте на модем sms-команду “?” и приложите текст ответа модема на эту команду.

Что вы имеете в виду под журналом работы Службы опроса? В журнале сеанса опроса ее естественно нет. Она отображается на вкладке сеансов связи модема с системой.
tmp_23073-Screenshot_20170727-0901302093770368.png
журнал.png
статистика.png

Вы приложили журнал опроса, тогда как я у вас запрашивал журнал работы Службы опроса. Пожалуйста приложите.

Папки PollHost нет, есть PollService.

PollService_Error.2017-07-26.log
2017-07-26 06:40:05.586 E:3236 Ошибка подключения GPRS-модема ЛЭРС GSM Plus.Ошибка идентификации GPRS-модема LersGsmPlus. Пакет идентификации ЛЭРС GSM считан не полностью GprsPoller
2017-07-26 06:40:09.770 E:3236 Ошибка подключения GPRS-модема ЛЭРС GSM Plus.Ошибка идентификации GPRS-модема LersGsmPlus. Пакет идентификации ЛЭРС GSM считан не полностью GprsPoller

PollService.2017-07-26.log
2017-07-26 06:40:01.574 I:2516 Открыто новое GPRS-подключение с IP-адреса 62.210.хх.хх:61742. Локальный IP-адрес: 10.хх.хх.30:ххх20 GprsPoller
2017-07-26 06:40:01.574 D:2516 Для нового подключения GprsClientId=38681 GprsPoller
2017-07-26 06:40:01.574 I:3236 Дамп обмена с модемом протоколируется в файл ‘C:ProgramDataLERSLogsPollServicedump.20(1).2017-07-26.log’ на компьютере ‘ХХХ’ GprsPoller
2017-07-26 06:40:02.231 D:2900 Отправляем данные в порт (1 байт)
2017-07-26 06:40:02.231 D:2900 COM1: Отправлено 1 байт
2017-07-26 06:40:04.796 D:2900 Отправляем данные в порт (1 байт)
2017-07-26 06:40:04.796 D:2900 COM1: Отправлено 1 байт
2017-07-26 06:40:05.586 E:3236 Ошибка подключения GPRS-модема ЛЭРС GSM Plus.Ошибка идентификации GPRS-модема LersGsmPlus. Пакет идентификации ЛЭРС GSM считан не полностью GprsPoller
2017-07-26 06:40:05.587 I:3236 Закрывается GPRS-подключение GprsClientId=38681, GprsDeviceId=<нет>, State=Connected GprsPoller
2017-07-26 06:40:05.765 I:2516 Открыто новое GPRS-подключение с IP-адреса 62.210.хх.хх:63036. Локальный IP-адрес: 10.хх.хх.30:ххх20 GprsPoller
2017-07-26 06:40:05.765 D:2516 Для нового подключения GprsClientId=38682 GprsPoller
2017-07-26 06:40:05.765 I:3236 Дамп обмена с модемом протоколируется в файл ‘C:ProgramDataLERSLogsPollServicedump.20(1).2017-07-26.log’ на компьютере ‘ХХХ’ GprsPoller
2017-07-26 06:40:06.523 D:2900 Отправляем данные в порт (1 байт)
2017-07-26 06:40:06.523 D:2900 COM1: Отправлено 1 байт
2017-07-26 06:40:08.049 D:2900 Отправляем данные в порт (1 байт)
2017-07-26 06:40:08.049 D:2900 COM1: Отправлено 1 байт
2017-07-26 06:40:08.049 D:2900 Карат-011: Получено 255 значений параметра P2
2017-07-26 06:40:08.049 I:2900 Карат-011: Считан архив значений по параметру ‘P2’
2017-07-26 06:40:09.614 D:2900 Отправляем данные в порт (133 байт)
2017-07-26 06:40:09.730 D:2900 COM1: Отправлено 133 байт
2017-07-26 06:40:09.770 E:3236 Ошибка подключения GPRS-модема ЛЭРС GSM Plus.Ошибка идентификации GPRS-модема LersGsmPlus. Пакет идентификации ЛЭРС GSM считан не полностью GprsPoller
2017-07-26 06:40:09.771 I:3236 Закрывается GPRS-подключение GprsClientId=38682, GprsDeviceId=<нет>, State=Connected GprsPoller

dump.31(1).2017-07-26.log (3.96 KB)

В приложенной выдержке из журнала работы Службы опроса речь идет о дампе обмена dump.20(1).2017-07-26.log, тогда как вы прислали дамп обмена dump.31(1).2017-07-26.log.

Пожалуйста пришлите файл журнала работы Службы опроса и все дампы обмена за тот же день.

  1. В настоящий момент к серверу подключено 33 модема GPRS, каждый сидит на своем порту. Модемы, которые работают стабильно имеют настройку AUTOCONNECTPERIOD,00:01-23:59, т.е. в течении дня не переподключаются. Модемы имеющие проблемы с идентификацией имеют настройку AUTOCONNECTPERIOD,“00:15-23:50!60” и перестают определяться после очередного переподключения. С 0 часов, насколько видно по логам, всегда происходит подключение.
  2. Модемы имеют разных операторов связи (МТС, МОТИВ), сбои происходят синхронно, насколько это возможно при опросе раз в час.
  3. Файл PollService.****.log имеет размер ~1.4-1.7Gb и внутренние разрывы (отсутствие записей за несколько часов) или оканчивается в 18-19 часов.

2017-07-26 11:03:09.629 I:1972 Карат-011: Подсистема: 1. Параметры: Время архивной записи; Время наработки; G1; T1; P1; E1;
2017-07-26 11:03:09.634 D:1140 Отправляем данные в порт (1 байт)
2017-07-26 11:03:09.634 D:1140 IP0: Отправлено 1 байт
2017-07-26 11:03:09.634 D:1140 Карат-011: Получено 255 значений параметра T2
2017-07-26 18:36:11.875 D:3504 ПСЧ-3ТМ.05М: Адрес текущего блока профиля мощности: 2032
2017-07-26 18:36:11.875 D:3504 ПСЧ-3ТМ.05М: Индекс текущего блока профиля мощности: 254
2017-07-26 18:36:11.875 D:3504 ПСЧ-3ТМ.05М: Чтение заголовка записи профиля мощности. Индекс: 0
2017-07-26 18:36:11.875 D:3504 Отправляем данные в порт (8 байт)

Насколько понял по логам разрывы возникают в тот момент, когда происходит Ошибка идентификации. Т.е. вызвана ли она сбоем лога или лог подвисает из-за нее. В предыдущем посте кусок дампа с ошибкой не стыкуется с логом подключения GPRS модема. Там на это время подключение отсутствует. Предыдущее и следующее прошло нормально. Ошибка появилась в 11, потом в 19 нормальное соединение, при этом опрос прибора прошел, затем вновь до 1:11 (первого соединения) следующего дня ошибки.
4. Добавил запрошенные файлы дампа порта опроса.
лог gprs по модему 20 порта.png
dump.20(2).2017-07-26.log (96 KB)
dump.20(1).2017-07-26.log (124 KB)

Мы проанализировали присланный дамп обмена. В указанное время вам приходит вовсе не пакет с идентификационными данными модема, а HTTP (GET) запрос следующего содержания:
GET / HTTP/1.1
Host: xx.xx.xx.68:xxx20
Connection: Keep-Alive

Выясните, пожалуйста, с какого IP-адреса приходят данные HTTP запросы и относятся ли эти адреса к вашим модемам.

Нормально так-то это адрес и порт на который коннектится этот модем, и желательно его удалить из предыдущего комментария.
Время с ошибками на приложенном рисунке и в логе вообще никаких байт нет, а лог PollService на это время не велся почему-то.
26.07.2017 11:11:04.615 ----- Порт открыт -----
26.07.2017 11:16:04.103 ----- Порт открыт -----
26.07.2017 11:21:04.223 ----- Порт открыт -----
26.07.2017 11:26:03.967 ----- Порт открыт -----
26.07.2017 11:31:03.658 ----- Порт открыт -----
26.07.2017 11:36:03.603 ----- Порт открыт -----
26.07.2017 11:41:03.583 ----- Порт открыт -----

1.Выяснить нет возможности.
2. Ситуация решилась следующим образом (по крайней мере в этом месяце проблема не возникала):
На диске с ЛЭРС оставалось свободными 2Гб в момент проверки, т.е. в рабочее время. Файл лога ~1.7Гб и имеет необъяснимые пропуски во всех случаях возникновения проблемы. Увеличили свободное место на диске до 20 Гб, проблема перестала возникать.
Похоже какие то системные трудности возникают у ОС с выделением места для файла или виртуальной памяти для своих задач. А ЛЭРС при невозможности записи в файл, релокации и т.п. (не знаю как у вас служба логирования работает с файлом лога) просто не принимает входящие соединения по GPRS.

Нет это не так. При подобных ошибках Службой опроса нам автоматически отправляется отчет об ошибке. Подобные ошибки не влияют на принятие/непринятие Службой опроса входящих соединений.

В вашем случае Службе опроса приходят именно HTTP запросы с неизвестного IP адреса, которые закономерно отвергается службой ввиду несоответствия оных идентификационному пакету модема.

  1. Каким образом мы можем идентифицировать источник этих пакетов? Насколько я понял он не логируется ЛЭРС.
  2. Почему PollService.log содержал пропуски именно на время сбоя приема подключений, при этом модемы не имеющие в настройках переподключения каждый час работали нормально, как и соединения через проводной интернет.
  3. Дайте ключевые параметры поиска данного паттерна, посмотрим есть ли он сейчас в логе.
  4. Если модем настроен переподключаться каждый час, тогда почему в дампах обмена по подключению нет записей коннектов после 19:09.
  1. Этот вопрос вы можете решить с вашим системным администратором.
  2. Не имею представления о каких пропусках вы говорите. Вы прикладывали лишь выдержку из журнала PollService.log. Также я не имею доступа к вашим модемам и не знаю когда именно ваши модемы подключались к вашему серверу.
  3. О каких паттернах вы говорите? HTTP пакета?
  4. Я не имею доступа к вашим модемам и не знаю когда именно ваши модемы подключались к вашему серверу.

Вам нужно выяснить с каких IP адресов приходят данные HTTP запросы и относятся ли данные IP адреса к вашим модемам. Это очень важно. Без этого дальнейший анализ не имеет смысла.

  1. Этот вопрос вы можете решить с вашим системным администратором.

Его ответ такой возможности нет.

  1. Не имею представления о каких пропусках вы говорите. Вы прикладывали лишь выдержку из журнала PollService.log.

Эта выдержка содержит неразрывный кусок. Т.е. после

2017-07-26 11:03:09.634 D:1140 Карат-011: Получено 255 значений параметра T2
2017-07-26

идет сразу же

18:36:11.875 D:3504 ПСЧ-3ТМ.05М: Адрес текущего блока профиля мощности: 2032

И где записи с 11:03 до 18:36? Их нет, причем всех не только GPRS но и проводных и CSD соединений.

  1. Я не имею доступа к вашим модемам и не знаю когда именно ваши модемы подключались к вашему серверу.

Это видно по представленным дампам обмена по порту опроса. Там нет записей после 19:09. А модем настроен

Модемы имеющие проблемы с идентификацией имеют настройку AUTOCONNECTPERIOD,“00:15-23:50!60” и перестают определяться после очередного переподключения. С 0 часов, насколько видно по логам, всегда происходит подключение.

и должен каждый час соединяться.
Я собственно ни на чем не настаиваю. Проблему решили. Но вам на заметку при свободном месте на диске ~равном файлу PollService.log происходит подобная проблема. Вы не верите, ваше право.

Мы попробуем воспроизвести ситуацию. Если она у нас проявится, мы обязательно ее исправим.