Ticket #446: messages_log

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