Ticket #446: messages

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