Ticket #446: messages.2

File messages.2, 16.6 KB (added by roman_zhur, 8 days ago)
Line 
1Jan 14 05:07:23 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
2Jan 14 05:07:23 sip_ua[477]: poller.cpp:890: ===> command globalconf received
3Jan 14 05:07:23 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
4Jan 14 05:07:23 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
5Jan 14 05:07:23 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
6Jan 14 05:07:23 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0002, data=0
7Jan 14 05:07:23 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
8Jan 14 05:07:23 sip_ua[477]: poller.cpp:890: ===> command userlist received
9Jan 14 05:07:23 sip_ua[477]: repro.cpp:1958: User directory updated
10Jan 14 05:07:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
11Jan 14 05:07:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=7
12Jan 14 05:07:24 sip_ua[477]: poller.cpp:2429: duplicate request 204 (321 ms)
13Jan 14 05:07:24 sip_ua[477]: poller.cpp:2429: duplicate request 205 (330 ms)
14Jan 14 05:07:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: Channel settings, ts=254, flags=0000, data=0
15Jan 14 05:07:24 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
16Jan 14 05:07:24 sip_ua[477]: poller.cpp:2429: duplicate request 207 (15 ms)
17Jan 14 05:07:25 sip_ua[477]: poller.cpp:2429: duplicate request 206 (255 ms)
18Jan 14 05:07:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=0
19Jan 14 05:07:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
20Jan 14 05:07:25 sip_ua[477]: poller.cpp:890: ===> command sslapply received
21Jan 14 05:07:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
22Jan 14 05:07:25 sip_ua[477]: poller.cpp:890: ===> command userlist received
23Jan 14 05:07:25 sip_ua[477]: repro.cpp:1958: User directory updated
24Jan 14 05:07:25 sip_ua[477]: poller.cpp:890: ===> command ctime received
25Jan 14 05:07:26 sip_ua[477]: poller.cpp:890: ===> command callgroups received
26Jan 14 05:07:26 sip_ua[477]: poller.cpp:890: ===> command routes received
27Jan 14 05:07:26 sip_ua[477]: poller.cpp:890: ===> command conferences received
28Jan 14 05:07:26 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
29Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Channel settings, ts=-1, flags=0001, data=0
30Jan 14 05:07:26 sip_ua[454]: virtualChannel.cpp:139: ---> VirtualChannelManager: conference '00' found
31Jan 14 05:07:26 sip_ua[454]: conference.cpp:657: ---> StaticConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
32Jan 14 05:07:26 sip_ua[454]: conference.cpp:284: ---> ConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
33Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
34Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
35Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
36Jan 14 05:07:26 sip_ua[454]: fxs.cpp:275: ts 254: dialing 022
37Jan 14 05:07:26 sip_ua[454]: user_agent.cpp:3892: --> ua_dial_out() <sip:disp@192.168.0.121> -> sip:022@192.168.0.121...
38Jan 14 05:07:26 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK740493288'
39Jan 14 05:07:26 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=8, did=9, tid=10, rid=0, sid=0, nid=0
40Jan 14 05:07:26 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:022@127.0.0.1:6060;transport=udp (Call-ID: 1161442777@(null))
41Jan 14 05:07:26 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=8
42Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0001, data=8
43Jan 14 05:07:26 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0001, data=8
44Jan 14 05:07:26 sip_ua[454]: virtualChannel.cpp:217: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=8
45Jan 14 05:07:26 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=8
46Jan 14 05:07:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0002, data=8
47Jan 14 05:07:26 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0002, data=8
48Jan 14 05:07:26 sip_ua[454]: conference.cpp:752: ---> ConferenceParticipant[1002]: Incoming call, ts=1002, flags=0006, data=8
49Jan 14 05:07:26 sip_ua[454]: conference.cpp:548: --> checkpoint 1: '22'
50Jan 14 05:07:26 sip_ua[454]: conference.cpp:552: --> checkpoint 2: '22@192.168.0.121'
51Jan 14 05:07:26 kernel: comcerto_wdt: closed unexpectedly. WDT will not stop!
52Jan 1 00:00:14 syslogd started: BusyBox v1.18.5
53Jan 1 00:00:14 kernel: klogd started: BusyBox v1.18.5 (2024-08-18 17:59:54 YEKT)
54Jan 1 00:00:14 kernel: Linux version 2.6.22.19-4.07.0-candidate1-c300evm (alx@alx) (gcc version 5.3.0 (GCC) ) #1 Fri Mar 18 16:22:33 YEKT 2022
55Jan 1 00:00:14 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
56Jan 1 00:00:14 kernel: Machine: Comcerto 300 (x570)
57Jan 1 00:00:14 kernel: Memory policy: ECC disabled, Data cache writeback
58Jan 1 00:00:14 kernel: On node 0 totalpages: 28416
59Jan 1 00:00:14 kernel: DMA zone: 222 pages used for memmap
60Jan 1 00:00:14 kernel: DMA zone: 0 pages reserved
61Jan 1 00:00:14 kernel: DMA zone: 28194 pages, LIFO batch:7
62Jan 1 00:00:14 kernel: Normal zone: 0 pages used for memmap
63Jan 1 00:00:14 kernel: CPU0: D VIPT write-back cache
64Jan 1 00:00:14 kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
65Jan 1 00:00:14 kernel: TCP: Hash tables configured (established 4096 bind 4096)
66Jan 1 00:00:14 kernel: TCP reno registered
67Jan 1 00:00:14 kernel: msp-driver: version 0.1
68Jan 1 00:00:14 kernel: JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
69Jan 1 00:00:14 kernel: io scheduler noop registered
70Jan 1 00:00:14 kernel: io scheduler deadline registered (default)
71Jan 1 00:00:14 kernel: comcerto_wdt: support registered
72Jan 1 00:00:14 kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
73Jan 1 00:00:14 kernel: serial8250.0: ttyS0 at MMIO 0x10090000 (irq = 41) is a 16550A
74Jan 1 00:00:14 kernel: serial8250.0: ttyS1 at MMIO 0x10094000 (irq = 57) is a 16550A
75Jan 1 00:00:16 sysinit: brctl: SIOCGIFBR: Package not installed
76Jan 1 00:00:16 sysinit: sh: bad number
77Jan 1 00:00:16 sysinit: sh: bad number
78Jan 1 00:00:16 sysinit: sh: bad number
79Jan 1 00:00:17 sysinit: sh: bad number
80Jan 1 00:00:17 sysinit: sh: bad number
81Jan 1 00:00:17 sysinit: sh: bad number
82Jan 1 00:00:17 sysinit: sh: bad number
83Jan 1 00:00:17 sysinit: sh: bad number
84Jan 1 00:00:17 dnsmasq[376]: started, version 2.89 cachesize 150
85Jan 1 00:00:17 dnsmasq[376]: compile time options: IPv6 GNU-getopt no-DBus no-UBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack no-ipset no-nftset no-auth no-cryptohash no-DNSSEC loop-detect no-inotify dumpfile
86Jan 1 00:00:17 dnsmasq[376]: using only locally-known addresses for lan
87Jan 1 00:00:17 dnsmasq[376]: no servers found in /tmp/resolv.conf.auto, will retry
88Jan 1 00:00:17 dnsmasq[376]: read /etc/hosts - 1 names
89Jan 1 00:00:18 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x03067000: read 0xafe1716a, calculated 0xdb059f6f.
90Jan 1 00:00:18 kernel: NET: Registered protocol family 27
91Jan 1 00:00:19 dropbear[427]: Failed loading /etc/dropbear/dropbear_dss_host_key
92Jan 1 00:00:19 dropbear[428]: Running in background
93Jan 1 00:00:20 kernel: JFFS2 notice: (432) check_node_data: wrong data CRC in data node at 0x09579800: read 0x8f42d75c, calculated 0x2ef33935.
94Jan 1 00:00:20 kernel: JFFS2 notice: (432) check_node_data: wrong data CRC in data node at 0x05023000: read 0xf7a6e6cc, calculated 0x3ae6fc07.
95Jan 1 00:00:21 sysinit: sh: bad number
96Jan 1 00:00:21 sysinit: sh: bad number
97Jan 1 00:00:21 sysinit: sh: bad number
98Jan 1 00:00:21 sysinit: sh: bad number
99Jan 1 00:00:21 sysinit: sh: bad number
100Jan 1 00:00:21 sysinit: sh: bad number
101Jan 1 00:00:21 sysinit: sh: bad number
102Jan 1 00:00:21 sysinit: sh: bad number
103Jan 1 00:00:21 sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
104Jan 1 00:00:21 dnsmasq[443]: failed to create listening socket for port 53: Address already in use
105Jan 1 00:00:21 dnsmasq[443]: FAILED to start up
106Jan 1 00:00:22 sysinit: starting SIP UA daemon: sip_ua...net.unix.max_dgram_qlen = 2048
107Jan 1 00:00:22 sysinit: done.
108Jan 1 00:00:23 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
109Jan 1 00:00:23 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
110Jan 1 00:00:23 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
111Jan 1 00:00:23 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
112Jan 1 00:00:23 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
113Jan 1 00:00:27 kernel: eth1: no IPv6 routers present
114Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:554: starting sip_ua
115Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:555: main thread PID is 454
116Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
117Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: f579f7ec, computed: b612b212
118Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:137: Fw_env: cannot open /dev/mtd6: No such file or directory
119Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
120Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: f579f7ec, computed: f579f7ec
121Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:568: serial number not found
122Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:586: Waiting for jffs2 scan finish and CPU idle time > 90%...
123Jan 1 00:00:31 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x04ef2800: read 0xd00ac606, calculated 0x7a9cbb52.
124Jan 1 00:00:32 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
125Jan 1 00:00:33 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
126Jan 1 00:00:34 sip_ua[454]: sip_ua.cpp:595: CPU idle 91%
127Jan 1 00:00:34 sip_ua[454]: scheduler.cpp:77: Initializing scheduling API
128Jan 1 00:00:34 sip_ua[454]: scheduler.cpp:107: Created schedule main scheduler
129Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5105: ==> SFKEYS: 0460 0305 bfec fef7
130Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5491: Comcerto C300 device: M82359 Rev.A
131Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5492: Comcerto C300 firmware version: v11_26_03_08_SS_04
132Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5493: Comcerto C300 SPU version: C64V_7_10_13
133Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5494: Device initialized successfully
134Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5501: TDM initialized successfully
135Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5384: Device mac address: 02:ad:c5:00:00:69
136Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5521: Channels available: 160 (G.711 20ms)
137Jan 1 00:00:35 sip_ua[458]: regSyncAgent.cpp:460: loading regDataBase from /lib/sip_ua/regDataBase.xml...
138Jan 1 00:00:35 sip_ua[458]: regSyncAgent.cpp:489: file timestamp is 1736830868 sec in the future
139Jan 14 05:01:43 sip_ua[458]: regSyncAgent.cpp:525: 2 object(s) loaded
140Jan 14 05:01:43 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
141Jan 14 05:01:43 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
142Jan 14 05:01:45 sip_ua[454]: poller.cpp:3404: firmware revision: 52
143Jan 14 05:01:45 sip_ua[477]: poller.cpp:2786: Configuring FPGA...
144Jan 14 05:01:45 sip_ua[477]: poller.cpp:2795: FPGA configured successfully
145Jan 14 05:01:45 sip_ua[477]: poller.cpp:2839: mofule 1: FS01 found
146Jan 14 05:01:45 sip_ua[477]: poller.cpp:2879: mofule 2: unknown module found (type=15)
147Jan 14 05:01:45 sip_ua[477]: poller.cpp:2895: board address is 1
148Jan 14 05:01:45 sip_ua[454]: mediaProxy.cpp:31: --> MediaProxy::start()
149Jan 14 05:01:49 sip_ua[477]: poller.cpp:2513: switching to CRC32 mode
150Jan 14 05:01:49 sip_ua[477]: poller.cpp:890: ===> command globalconf received
151Jan 14 05:01:49 dnsmasq[376]: reading /tmp/resolv.conf.auto
152Jan 14 05:01:49 dnsmasq[376]: using nameserver 8.8.8.8#53
153Jan 14 05:01:49 dnsmasq[376]: using only locally-known addresses for lan
154Jan 14 05:01:49 sip_ua[477]: comcerto.cpp:2217: !!!!! function IP_ADDRESS_v6 (0x0310): error CNF_ERROR_IP_ADDRESS_BAD_IP_ADDRESS (0x0b03):
155Jan 14 05:01:49 sip_ua[477]: comcerto.cpp:2218: Invalid IP Address
156Jan 14 05:01:49 sip_ua[477]: comcerto.cpp:5282: comcerto_set_ip6() failed (result=-2819)
157Jan 14 05:01:49 sip_ua[477]: poller.cpp:1431: downloading sound files from http://192.168.1.104/files/ve-01/sounds...
158Jan 14 05:01:50 sip_ua[454]: sip_ua.cpp:875: IP change 127.0.0.1 --> 192.168.0.121 detected
159Jan 14 05:01:50 sip_ua[477]: poller.cpp:2429: duplicate request 238 (84 ms)
160Jan 14 05:01:51 sip_ua[477]: poller.cpp:2429: duplicate request 236 (373 ms)
161Jan 14 05:01:51 sip_ua[458]: regSyncAgent.cpp:893: client socket error (events=0x18)
162Jan 14 05:01:51 sip_ua[458]: regSyncAgent.cpp:903: cannot connect to repro: Connection refused
163Jan 14 05:09:01 sip_ua[477]: fs01.cpp:136: module 1: FS01 created at base address 0x0400
164Jan 14 05:09:01 sip_ua[477]: poller.cpp:2429: duplicate request 240 (0 ms)
165Jan 14 05:09:02 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
166Jan 14 05:09:02 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
167Jan 14 05:09:02 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
168Jan 14 05:09:02 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
169Jan 14 05:09:02 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: IP address changed, ts=-1, flags=0001, data=1
170Jan 14 05:09:02 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0001, data=1
171Jan 14 05:09:02 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: IP address changed, ts=-1, flags=0002, data=1
172Jan 14 05:09:02 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0002, data=1
173Jan 14 05:09:02 sip_ua[477]: fs01.cpp:333: module 1 [FS01]: cofidec Si3210 Revision E detected
174Jan 14 05:09:02 sip_ua[477]: poller.cpp:2429: duplicate request 242 (136 ms)
175Jan 14 05:09:02 sip_ua[477]: poller.cpp:2429: duplicate request 241 (263 ms)
176Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command sslapply received
177Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command userlist received
178Jan 14 05:09:02 sip_ua[477]: repro.cpp:1958: User directory updated
179Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command ctime received
180Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command callgroups received
181Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command routes received
182Jan 14 05:09:02 sip_ua[477]: fs01.cpp:376: module 1 [FS01]: DC-DC ON
183Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command conferences received
184Jan 14 05:09:02 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
185Jan 14 05:09:02 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
186Jan 14 05:09:02 sip_ua[454]: virtualChannel.cpp:130: ---> VirtualChannelManager: conference '00' created
187Jan 14 05:09:02 sip_ua[454]: virtualChannel.cpp:139: ---> VirtualChannelManager: conference '00' found
188Jan 14 05:09:02 sip_ua[454]: conference.cpp:657: ---> StaticConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
189Jan 14 05:09:02 sip_ua[454]: conference.cpp:284: ---> ConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
190Jan 14 05:09:02 sip_ua[477]: poller.cpp:890: ===> command registrations received
191Jan 14 05:09:02 sip_ua[477]: poller.cpp:1149: --> registrations updated.
192Jan 14 05:09:02 sip_ua[477]: fs01.cpp:390: module 1 [FS01]: DC-DC calibration started...
193Jan 14 05:09:02 sip_ua[477]: fs01.cpp:427: module 1 [FS01]: SLIC calibration started...
194Jan 14 05:09:04 sip_ua[477]: poller.cpp:3273: download complete
195Jan 14 05:09:06 sip_ua[477]: fs01.cpp:541: module 1 [FS01]: SLIC calibration 2 started...
196Jan 14 05:09:06 sip_ua[477]: fs01.cpp:626: module 1 [FS01]: init complete
197Jan 14 05:09:07 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
198Jan 14 05:09:09 kernel: eth2: no IPv6 routers present
199Jan 14 05:10:29 dropbear[525]: Child connection from 192.168.0.142:50320
200Jan 14 05:10:30 dropbear[525]: Pubkey auth succeeded for 'root' with ssh-rsa key SHA256:8i65UsekgFf6uAr6cmxT7M+qcX1JHrmsBe6AQj5aJ9c from 192.168.0.142:50320