Opened 3 years ago

Closed 3 years ago

#360 closed баг (не воспроизводится)

После перезапуска VE-01 пропали регистрации

Reported by: san Owned by: alx
Priority: средний Milestone: 1 очередь
Component: any Keywords:
Cc:

Description

Пользователь сообщает, что плата VE-01 по непонятным для него причинам на минуту перестала отвечать SW-01(предположительно перезапустилась).
Через примерно 9 часов после этого события пользователь случайно обнаружил, что у всех Sip-пользователей, которые должны регистрироваться на плате VE-01 отсутствует регистрация. До перезапуска регистрация присутствовала.
Обнаружив отсутствие регистрации, пользователь нажал кнопку Применить на плате VE-01 и только после этого, по словам пользователя, Sip-пользователи начали регистрироваться на плате.

Время перезапуска VE-01: Mar 24 20:59:15 UTC
Время нажатия Применить: Mar 25 04:55:21 UTC
Версия sw: 1.0-r1975, ve-01 ревизия прошивки 62.
Лог SW-01:

Mar 24 18:30:30 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616610556,"duration":70,"hangup":1616610626,"org-host":"10.12.223.6","org-name":"Дисп. УРС-1 (Семёновский РЭС)","org-scheme":"sip","org-user":"2704","to-h
Mar 24 20:43:04 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616618559,"duration":22,"hangup":1616618581,"org-host":"10.12.223.6","org-name":"УРС-3(ГЭС,Килемары,Юрино)(89600910022)","org-scheme":"tel","org-type":"FXO"
Mar 24 20:43:04 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616618559,"duration":22,"hangup":1616618581,"org-host":"10.12.223.6","org-name":"УРС-3(ГЭС,Килемары,Юрино)(89600910022)","org-scheme":"tel","org-type":"FXO"
Mar 24 20:59:15 sw01 daemon.info swd[254]: slot 16: board VE-01 lost in space
Mar 24 20:59:15 sw01 daemon.info swd[254]: slot 16: switching to XOR8 mode
Mar 24 20:59:16 sw01 daemon.info swd[254]: slot 9: start alarm ethernet port 10: NO LINK
Mar 24 20:59:16 sw01 daemon.info swd[254]: slot 9: start alarm ALARM (Общая авария платы)
Mar 24 21:00:13 sw01 daemon.info swd[254]: slot 16: switching to CRC32 mode
Mar 24 21:00:13 sw01 daemon.info swd[254]: New board VE-01 in slot 16
Mar 24 21:00:14 sw01 daemon.info swd[254]: slot 9: end alarm ethernet port 10: NO LINK (duration 58 s)
Mar 24 21:00:14 sw01 daemon.info swd[254]: slot 9: end alarm ALARM (Общая авария платы) (duration 58 s)
Mar 25 03:46:50 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616643995,"duration":13,"hangup":1616644008,"org-host":"10.12.223.5","org-name":"1128 (УРС-2(Cоветск,Оршанка,Н.Торьял))","org-scheme":"tel","org-type":"
Mar 25 03:46:50 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616643995,"duration":13,"hangup":1616644008,"org-host":"10.12.223.5","org-name":"1128 (УРС-2(Cоветск,Оршанка,Н.Торьял))","org-scheme":"tel","org-type":"
Mar 25 03:59:22 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644741,"duration":19,"hangup":1616644760,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","o
Mar 25 03:59:22 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644741,"duration":19,"hangup":1616644760,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","o
Mar 25 03:59:47 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644762,"duration":23,"hangup":1616644785,"org-host":"10.12.223.5","org-name":"1152 (УРС-3(ГЭС,Килемары,Юрино))","org-scheme":"tel","org-type":"FXO","org-
Mar 25 03:59:47 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644762,"duration":23,"hangup":1616644785,"org-host":"10.12.223.5","org-name":"1152 (УРС-3(ГЭС,Килемары,Юрино))","org-scheme":"tel","org-type":"FXO","org-
Mar 25 04:01:00 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644828,"duration":30,"hangup":1616644858,"org-host":"10.12.223.6","org-name":"УРС-2(Cоветск,Оршанка,Н.Торьял) 89093671222","org-scheme":"tel","org-ty
Mar 25 04:01:01 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644828,"duration":30,"hangup":1616644858,"org-host":"10.12.223.6","org-name":"УРС-2(Cоветск,Оршанка,Н.Торьял) 89093671222","org-scheme":"tel","org-ty
Mar 25 04:01:43 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644873,"duration":28,"hangup":1616644901,"org-host":"10.12.223.6","org-name":"УРС-5( Волжский,Звениговсвкий,Моркинский) 89093674777","org-
Mar 25 04:01:43 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644873,"duration":28,"hangup":1616644901,"org-host":"10.12.223.6","org-name":"УРС-5( Волжский,Звениговсвкий,Моркинский) 89093674777","org-
Mar 25 04:02:45 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644937,"duration":26,"hangup":1616644963,"org-host":"10.12.223.5","org-name":"1152 (УРС-3(ГЭС,Килемары,Юрино))","org-scheme":"tel","org-type":"FXO","org-
Mar 25 04:02:45 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616644937,"duration":26,"hangup":1616644963,"org-host":"10.12.223.5","org-name":"1152 (УРС-3(ГЭС,Килемары,Юрино))","org-scheme":"tel","org-type":"FXO","org-
Mar 25 04:04:49 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645072,"duration":15,"hangup":1616645087,"org-host":"10.12.223.6","org-name":"УРС-5( Волжский,Звениговсвкий,Моркинский) 89093674777","org-
Mar 25 04:04:49 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645072,"duration":15,"hangup":1616645087,"org-host":"10.12.223.6","org-name":"УРС-5( Волжский,Звениговсвкий,Моркинский) 89093674777","org-
Mar 25 04:06:00 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645130,"duration":28,"hangup":1616645158,"org-host":"10.12.223.6","org-scheme":"tel","org-type":"PRI","org-user":"681100","to-host":"10.12.223.6","to-scheme":"sip","to-user
Mar 25 04:06:00 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645130,"duration":28,"hangup":1616645158,"org-host":"10.12.223.6","org-scheme":"tel","org-type":"PRI","org-user":"681100","to-host":"10.12.223.6","to-scheme":"sip","to-user
Mar 25 04:07:16 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645199,"duration":35,"hangup":1616645234,"org-host":"10.12.220.73","org-name":"Параньгинский РЭС","org-scheme":"sip","org-user":"2761","to-host":"10.12.220.
Mar 25 04:07:16 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645199,"duration":35,"hangup":1616645234,"org-host":"10.12.220.73","org-name":"Параньгинский РЭС","org-scheme":"sip","org-user":"2761","to-host":"10.12.220.
Mar 25 04:09:41 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645359,"duration":20,"hangup":1616645379,"org-host":"10.12.223.6","org-name":"УРС-2(Cоветск,Оршанка,Н.Торьял) 89093671222","org-scheme":"tel","org-ty
Mar 25 04:09:41 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645359,"duration":20,"hangup":1616645379,"org-host":"10.12.223.6","org-name":"УРС-2(Cоветск,Оршанка,Н.Торьял) 89093671222","org-scheme":"tel","org-ty
Mar 25 04:10:07 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645388,"duration":17,"hangup":1616645405,"org-host":"10.12.223.6","org-name":"Дисп. УРС-2(советск,Оршанка,Н.Торьял)","org-scheme":"sip","org-use
Mar 25 04:10:07 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616645388,"duration":17,"hangup":1616645405,"org-host":"10.12.223.6","org-name":"Дисп. УРС-2(советск,Оршанка,Н.Торьял)","org-scheme":"sip","org-use
Mar 25 04:45:35 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647464,"duration":69,"hangup":1616647533,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","o
Mar 25 04:45:35 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647464,"duration":69,"hangup":1616647533,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","o
Mar 25 04:46:28 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647583,"duration":3,"hangup":1616647586,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","or
Mar 25 04:46:28 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647583,"duration":3,"hangup":1616647586,"org-host":"10.12.223.6","org-name":"УРС-1 (Семёновский РЭС) (89613361531)","org-scheme":"tel","org-type":"FXO","or
Mar 25 04:46:33 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647570,"duration":21,"hangup":1616647591,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:46:34 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647570,"duration":21,"hangup":1616647591,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:47:26 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647629,"duration":15,"hangup":1616647644,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:47:26 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616647629,"duration":15,"hangup":1616647644,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:53:28 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648001,"duration":5,"hangup":1616648006,"org-host":"10.12.223.5","org-name":"1128 (УРС-2(Cоветск,Оршанка,Н.Торьял))","org-scheme":"tel","org-type":"F
Mar 25 04:53:28 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648001,"duration":5,"hangup":1616648006,"org-host":"10.12.223.5","org-name":"1128 (УРС-2(Cоветск,Оршанка,Н.Торьял))","org-scheme":"tel","org-type":"F
Mar 25 04:55:21 sw01 daemon.info swd[254]: admin from [10.112.200.2]: writing variable(s) to slot 16
Mar 25 04:56:16 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648166,"duration":9,"hangup":1616648175,"org-host":"10.12.223.6","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2770","to-host":"10.12.223.6","to-s
Mar 25 04:56:16 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648166,"duration":9,"hangup":1616648175,"org-host":"10.12.223.6","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2770","to-host":"10.12.223.6","to-s
Mar 25 04:56:40 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648182,"duration":17,"hangup":1616648199,"org-host":"10.12.223.6","org-name":"Кропотов А.А.","org-scheme":"sip","org-user":"2798","to-host":"10.12.223.6","to-sche
Mar 25 04:56:40 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648182,"duration":17,"hangup":1616648199,"org-host":"10.12.223.6","org-name":"Кропотов А.А.","org-scheme":"sip","org-user":"2798","to-host":"10.12.223.6","to-sche
Mar 25 04:58:11 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648222,"duration":68,"hangup":1616648290,"org-host":"10.12.223.6","org-name":"УОС ДС ЦУС(89877079459)","org-scheme":"tel","org-type":"FXO","org-user":"27007","to-ho
Mar 25 04:58:11 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648222,"duration":68,"hangup":1616648290,"org-host":"10.12.223.6","org-name":"УОС ДС ЦУС(89877079459)","org-scheme":"tel","org-type":"FXO","org-user":"27007","to-ho
Mar 25 04:58:53 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648314,"duration":18,"hangup":1616648332,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:58:53 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648314,"duration":18,"hangup":1616648332,"org-host":"10.12.223.5","org-name":"ЦУС Мариэнерго","org-scheme":"sip","org-user":"2870","to-host":"10.12.223.5","to-
Mar 25 04:58:59 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648335,"duration":3,"hangup":1616648338,"org-host":"10.12.223.6","org-scheme":"tel","org-type":"PRI","org-user":"681198","to-host":"10.12.223.6","to-scheme":"sip","to-user"
Mar 25 04:59:00 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648335,"duration":3,"hangup":1616648338,"org-host":"10.12.223.6","org-scheme":"tel","org-type":"PRI","org-user":"681198","to-host":"10.12.223.6","to-scheme":"sip","to-user"
Mar 25 04:59:21 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648341,"duration":19,"hangup":1616648360,"org-host":"10.12.223.6","org-name":"Ленинская","org-scheme":"tel","org-type":"АДАСЭ","org-user":"2736","to-host":"10
Mar 25 04:59:21 sw01 daemon.info swd[254]: board_VE01.cpp:1314: slot 16: --> CDR: {"answer":1616648341,"duration":19,"hangup":1616648360,"org-host":"10.12.223.6","org-name":"Ленинская","org-scheme":"tel","org-type":"АДАСЭ","org-user":"2736","to-host":"10

Лог VE-01:

root@comcerto:~#  logread
Jan  1 00:00:10 comcerto syslog.info syslogd started: BusyBox v1.18.5
Jan  1 00:00:10 comcerto user.notice kernel: klogd started: BusyBox v1.18.5 (2020-09-30 13:01:41 YEKT)
Jan  1 00:00:10 comcerto user.notice kernel: Linux version 2.6.22.19-4.07.0-candidate1-c300evm (alx@ubuntu) (gcc version 5.3.0 (GCC) ) #4 Wed Mar 29 09:28:44 +05 2017
Jan  1 00:00:10 comcerto user.warn kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
Jan  1 00:00:10 comcerto user.warn kernel: Machine: Comcerto 300 (x570)
Jan  1 00:00:10 comcerto user.warn kernel: Memory policy: ECC disabled, Data cache writeback
Jan  1 00:00:10 comcerto user.debug kernel: On node 0 totalpages: 28416
Jan  1 00:00:10 comcerto user.debug kernel:   DMA zone: 222 pages used for memmap
Jan  1 00:00:10 comcerto user.debug kernel:   DMA zone: 0 pages reserved
Jan  1 00:00:10 comcerto user.debug kernel:   DMA zone: 28194 pages, LIFO batch:7
Jan  1 00:00:10 comcerto user.debug kernel:   Normal zone: 0 pages used for memmap
Jan  1 00:00:10 comcerto user.warn kernel: CPU0: D VIPT write-back cache
Jan  1 00:00:10 comcerto user.info kernel: 2(out=1)
Jan  1 00:00:10 comcerto user.info kernel: 0(irq=33, type=1, trigger=0)
Jan  1 00:00:10 comcerto user.info kernel: 3(irq=36, type=1, trigger=0)
Jan  1 00:00:10 comcerto user.info kernel: Generic PHY: Registered new driver
Jan  1 00:00:10 comcerto user.info kernel: NET: Registered protocol family 2
Jan  1 00:00:10 comcerto user.info kernel: Time: timer3 clocksource has been installed.
Jan  1 00:00:10 comcerto user.info kernel: Switched to high resolution mode on CPU 0
Jan  1 00:00:10 comcerto user.warn kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
Jan  1 00:00:10 comcerto user.warn kernel: TCP established hash table entries: 4096 (order: 3, 32768 bytes)
Jan  1 00:00:10 comcerto user.warn kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Jan  1 00:00:10 comcerto user.info kernel: TCP: Hash tables configured (established 4096 bind 4096)
Jan  1 00:00:11 comcerto user.info sysinit: brctl: SIOCGIFBR: Package not installed
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:12 comcerto user.notice kernel: JFFS2 notice: (215) check_node_data: wrong data CRC in data node at 0x06cc2800: read 0xa74cea90, calculated 0xc665572d.
Jan  1 00:00:12 comcerto daemon.info dnsmasq[330]: started, version 2.75 cachesize 150
Jan  1 00:00:12 comcerto daemon.info dnsmasq[330]: compile time options: no-IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect no-inotify
Jan  1 00:00:12 comcerto daemon.info dnsmasq[330]: using local addresses only for domain lan
Jan  1 00:00:12 comcerto daemon.warn dnsmasq[330]: no servers found in /tmp/resolv.conf.auto, will retry
Jan  1 00:00:12 comcerto daemon.info dnsmasq[330]: read /etc/hosts - 1 addresses
Jan  1 00:00:13 comcerto user.info kernel: NET: Registered protocol family 27
Jan  1 00:00:13 comcerto authpriv.info dropbear[349]: Running in background
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: sh: bad number
Jan  1 00:00:13 comcerto user.info sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
Jan  1 00:00:13 comcerto daemon.crit dnsmasq[358]: failed to create listening socket for port 53: Address already in use
Jan  1 00:00:13 comcerto daemon.crit dnsmasq[358]: FAILED to start up
Jan  1 00:00:14 comcerto user.info sysinit: starting SIP UA daemon: sip_ua... net.unix.max_dgram_qlen = 256
Jan  1 00:00:14 comcerto user.info sysinit: done.
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.netfilter.nf_conntrack_checksum' is an unknown key
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
Jan  1 00:00:14 comcerto user.info sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
Jan  1 00:00:22 comcerto user.debug kernel: eth1: no IPv6 routers present
Jan  1 00:00:23 comcerto daemon.warn sip_ua[372]: regSyncAgent.cpp:489: file timestamp is 1604054587 sec in the future
Oct 30 10:43:31 comcerto daemon.err sip_ua[394]: poller.cpp:2003: Configuring FPGA...
Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:2168: !!!!! function IP_ADDRESS_v6 (0x0310): error CNF_ERROR_IP_ADDRESS_BAD_IP_ADDRESS (0x0b03):
Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:2169: Invalid IP Address
Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:4993: comcerto_set_ip6() failed (result=-2819)
Oct 30 10:43:32 comcerto daemon.info dnsmasq[330]: reading /tmp/resolv.conf.auto
Oct 30 10:43:32 comcerto daemon.info dnsmasq[330]: using local addresses only for domain lan
Oct 30 10:43:32 comcerto daemon.info dnsmasq[330]: using nameserver 10.12.60.10#53
Oct 30 10:43:32 comcerto daemon.notice sip_ua[394]: poller.cpp:1235: downloading sound files from http://10.112.224.5/files/ve-01/sounds...
Oct 30 10:43:32 comcerto daemon.notice sip_ua[368]: sip_ua.cpp:547: IP change 127.0.0.1 --> 10.12.223.6 detected
Oct 30 10:43:36 comcerto daemon.warn sip_ua[368]: comcerto.cpp:2444: ---> channel 0x0004 (ts 35): unknown indication: FC=0x8020
Oct 30 10:43:36 comcerto daemon.warn sip_ua[368]: comcerto.cpp:2444: ---> channel 0x0008 (ts 39): unknown indication: FC=0x8020
Mar 24 21:00:22 comcerto daemon.warn sip_ua[394]: poller.cpp:907: routes: invalid offset 450 (expected 0)
Mar 24 21:00:23 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 450 (expected 900)
Mar 24 21:00:24 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 2250 (expected 2700)
Mar 24 21:00:25 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 2700 (expected 3150)
Mar 24 21:00:25 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 3150 (expected 3600)
Mar 24 21:00:26 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 3600 (expected 4050)
Mar 24 21:00:28 comcerto daemon.warn sip_ua[394]: poller.cpp:965: --> registrations updated.
Mar 24 22:05:37 comcerto daemon.err sip_ua[394]: poller.cpp:2378: wget exited with return code 1
Mar 25 04:55:25 comcerto daemon.warn sip_ua[394]: poller.cpp:907: routes: invalid offset 450 (expected 0)
Mar 25 04:58:37 comcerto daemon.warn sip_ua[368]: adase.cpp:463: ADASE[54]: unexpected short pulse received in state Connected
Mar 25 06:57:41 comcerto authpriv.info dropbear[427]: Child connection from ::ffff:10.112.200.2:54705
Mar 25 06:57:48 comcerto authpriv.notice dropbear[427]: pubkey auth succeeded for 'root' with key md5 81:37:78:b6:51:df:e8:34:6c:0b:ee:75:10:4d:da:67 from ::ffff:10.112.200.2:54705
Mar 25 06:59:02 comcerto authpriv.info dropbear[427]: exit after auth (root): Exited normally
Mar 25 07:04:33 comcerto authpriv.info dropbear[434]: Child connection from ::ffff:10.112.200.2:54770
Mar 25 07:04:37 comcerto authpriv.notice dropbear[434]: pubkey auth succeeded for 'root' with key md5 81:37:78:b6:51:df:e8:34:6c:0b:ee:75:10:4d:da:67 from ::ffff:10.112.200.2:54770

Change History (3)

comment:1 by alx, 3 years ago

Здесь я вижу сразу несколько "странностей".

Во-первых, странная ошибка при установке MSP адреса:

Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:2168: !!!!! function IP_ADDRESS_v6 (0x0310): error CNF_ERROR_IP_ADDRESS_BAD_IP_ADDRESS (0x0b03):
Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:2169: Invalid IP Address
Oct 30 10:43:32 comcerto daemon.err sip_ua[394]: comcerto.cpp:4993: comcerto_set_ip6() failed (result=-2819)

Что же это за адрес такой был, который MSP посчитал invalid? Во-вторых, wget о чем-то думал целый час (!), после чего завершился с кодом 1:

Mar 24 22:05:37 comcerto daemon.err sip_ua[394]: poller.cpp:2378: wget exited with return code 1

Это наводит на мысль о каких-то сетевых проблемах, но непонятно, почему тогда код 1 (Generic error code), а не 4 (Network failure)...

В-третьих, серия сообщений типа

Mar 24 21:00:25 comcerto daemon.warn sip_ua[394]: poller.cpp:946: registrations: invalid offset 2700 (expected 3150)

говорит о том, что плата получала по шине управления повторы уже обработанных сообщений. Это наводит на мысль о потере передаваемых по шине сообщений. Я бы рекомендовал понаблюдать на вкладке "Сервис" платы SW-01 за счетчиками плохих пакетов. Однако проблемы с шиной вряд ли могли привести к нерегистрации пользователей...

Известно ли, что UAC'и получали в ответ на запрос регистрации?

comment:2 by alx, 3 years ago

Провел эксперимент: запустил wget скачать URL с компьютера, не устанавливающего соединения на порт 80. В результате wget работал 66 минут (сделал 20 попыток соединения), что соответствует имеющимся записям лога.

comment:3 by alx, 3 years ago

Resolution: не воспроизводится
Status: newclosed
Note: See TracTickets for help on using tickets.