Ticket #448: messages1

File messages1, 27.2 KB (added by roman_zhur, 12 days ago)
Line 
1Feb 10 05:00:22 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
2Feb 10 05:00:22 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=7
3Feb 10 05:00:24 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=21, did=0, tid=0, rid=0, sid=0, nid=0
4Feb 10 05:00:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=5
5Feb 10 05:00:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
6Feb 10 05:00:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
7Feb 10 05:00:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=5
8Feb 10 05:00:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
9Feb 10 05:00:27 sip_ua[454]: fxs.cpp:275: ts 254: dialing 55
10Feb 10 05:00:27 sip_ua[454]: user_agent.cpp:3892: --> ua_dial_out() <sip:11@192.168.20.69> -> sip:55@192.168.20.69...
11Feb 10 05:00:27 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK925447729'
12Feb 10 05:00:27 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=30, did=31, tid=25, rid=0, sid=0, nid=0
13Feb 10 05:00:27 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:55@127.0.0.1:6060;transport=udp (Call-ID: 1027699819@(null))
14Feb 10 05:00:27 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Idle: Incoming call, ts=-1, flags=0001, data=30
15Feb 10 05:00:27 sip_ua[454]: user_agent.cpp:4329: Call 30 routed to TS 0
16Feb 10 05:00:27 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=29, did=0, tid=24, rid=0, sid=0, nid=0
17Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Tone completed, ts=0, flags=0000, data=0
18Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:6461: channel 0: answer
19Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: RTP parameters, ts=0, flags=0000, data=30
20Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7297: --> ts 0: 192.168.20.69[10000] --> 192.168.20.69[10508]
21Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7298: --> ts 0: codec PCMA, VAD is on, red=0
22Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
23Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.69 to 192.168.20.69 (lo)
24Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.69): 02:ad:c5:00:00:69
25Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7423: ts 0: RTP destination is 02:ad:c5:00:00:69
26Feb 10 05:00:28 sip_ua[520]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK925447729'
27Feb 10 05:00:28 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=29, did=32, tid=24, rid=0, sid=0, nid=0
28Feb 10 05:00:28 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 12 (ACK received!): cid=30, did=31, tid=25, rid=0, sid=0, nid=0
29Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7122: ts 0: starting RTP stream
30Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=29
31Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7297: --> ts 254: 192.168.20.69[10508] --> 192.168.20.69[10000]
32Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7298: --> ts 254: codec PCMA, VAD is on, red=0
33Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
34Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.69 to 192.168.20.69 (lo)
35Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.69): 02:ad:c5:00:00:69
36Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7423: ts 254: RTP destination is 02:ad:c5:00:00:69
37Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7122: ts 254: starting RTP stream
38Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=29
39Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: RTP parameters, ts=0, flags=0000, data=30
40Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7297: --> ts 0: 192.168.20.69[10000] --> 192.168.20.69[10508]
41Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7298: --> ts 0: codec PCMA, VAD is on, red=0
42Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
43Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.69 to 192.168.20.69 (lo)
44Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.69): 02:ad:c5:00:00:69
45Feb 10 05:00:28 sip_ua[454]: comcerto.cpp:7423: ts 0: RTP destination is 02:ad:c5:00:00:69
46Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: ACK received, ts=0, flags=0000, data=30
47Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Statistics received, ts=0, flags=0000, data=0
48Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Statistics received, ts=0, flags=0000, data=0
49Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
50Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
51Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
52Feb 10 05:00:28 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: RTP monitor, ts=0, flags=0000, data=1
53Feb 10 05:00:28 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
54Feb 10 05:00:30 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---8fa1c62bcc389f4b'
55Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=33, did=34, tid=26, rid=0, sid=0, nid=0
56Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:123@127.0.0.1:6060;transport=udp (Call-ID: 1133803659@(null))
57Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=26, did=0, tid=0, rid=0, sid=0, nid=0
58Feb 10 05:00:30 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Incoming call, ts=-1, flags=0001, data=33
59Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Incoming call, ts=-1, flags=0001, data=33
60Feb 10 05:00:30 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0001, data=33
61Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: Incoming call, ts=1001, flags=0005, data=33
62Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: REFER received, ts=1002, flags=0000, data=0
63Feb 10 05:00:30 sip_ua[454]: user_agent.cpp:4329: Call 33 routed to TS 1001
64Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: Call ID replaced, ts=1002, flags=0000, data=0
65Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: RTP parameters, ts=1001, flags=0000, data=33
66Feb 10 05:00:30 sip_ua[454]: comcerto.cpp:7297: --> ts 1001: 192.168.20.69[12002] --> 0.0.0.0[10004]
67Feb 10 05:00:30 sip_ua[454]: comcerto.cpp:7298: --> ts 1001: codec PCMA, VAD is on, red=0
68Feb 10 05:00:30 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
69Feb 10 05:00:30 sip_ua[454]: comcerto.cpp:7143: ts 1001: stopping RTP stream
70Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
71Feb 10 05:00:30 sip_ua[520]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---8fa1c62bcc389f4b'
72Feb 10 05:00:30 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK2062636087'
73Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=36, did=37, tid=28, rid=0, sid=0, nid=0
74Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:11@127.0.0.1:6060;transport=udp (Call-ID: 1880126476@(null))
75Feb 10 05:00:30 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Incoming call, ts=-1, flags=0001, data=36
76Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Incoming call, ts=-1, flags=0001, data=36
77Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=36, did=-1, tid=28, rid=0, sid=0, nid=0
78Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!): cid=35, did=0, tid=27, rid=0, sid=0, nid=0
79Feb 10 05:00:30 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Call disconnected, ts=-1, flags=0001, data=36
80Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Call disconnected, ts=-1, flags=0001, data=36
81Feb 10 05:00:30 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Call disconnected, ts=-1, flags=0002, data=36
82Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Call disconnected, ts=-1, flags=0002, data=36
83Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: Call disconnected, ts=1002, flags=0000, data=35
84Feb 10 05:00:30 sip_ua[454]: comcerto.cpp:7143: ts 1002: stopping RTP stream
85Feb 10 05:00:30 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1002
86Feb 10 05:00:30 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: Call disconnected, ts=1001, flags=0000, data=-1
87Feb 10 05:00:30 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1001
88Feb 10 05:00:30 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=33, did=-1, tid=29, rid=0, sid=0, nid=0
89Feb 10 05:00:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
90Feb 10 05:00:33 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Statistics received, ts=0, flags=0000, data=0
91Feb 10 05:00:33 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Statistics received, ts=0, flags=0000, data=0
92Feb 10 05:00:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
93Feb 10 05:00:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
94Feb 10 05:00:34 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---9a564c64ef7d5055'
95Feb 10 05:00:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
96Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=38, did=39, tid=30, rid=0, sid=0, nid=0
97Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:123@127.0.0.1:6060;transport=udp (Call-ID: 1320346539@(null))
98Feb 10 05:00:34 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Incoming call, ts=-1, flags=0001, data=38
99Feb 10 05:00:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Incoming call, ts=-1, flags=0001, data=38
100Feb 10 05:00:34 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0001, data=38
101Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: Incoming call, ts=1001, flags=0005, data=38
102Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: REFER received, ts=1002, flags=0000, data=0
103Feb 10 05:00:34 sip_ua[454]: user_agent.cpp:4329: Call 38 routed to TS 1001
104Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: Call ID replaced, ts=1002, flags=0000, data=0
105Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: RTP parameters, ts=1001, flags=0000, data=38
106Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7297: --> ts 1001: 192.168.20.69[12002] --> 0.0.0.0[10006]
107Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7298: --> ts 1001: codec PCMA, VAD is on, red=0
108Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
109Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7143: ts 1001: stopping RTP stream
110Feb 10 05:00:34 sip_ua[520]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---9a564c64ef7d5055'
111Feb 10 05:00:34 sip_ua[520]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK2127907749'
112Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=41, did=42, tid=32, rid=0, sid=0, nid=0
113Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:11@127.0.0.1:6060;transport=udp (Call-ID: 1674427801@(null))
114Feb 10 05:00:34 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Incoming call, ts=-1, flags=0001, data=41
115Feb 10 05:00:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Incoming call, ts=-1, flags=0001, data=41
116Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=41, did=-1, tid=32, rid=0, sid=0, nid=0
117Feb 10 05:00:34 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Call disconnected, ts=-1, flags=0001, data=41
118Feb 10 05:00:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Call disconnected, ts=-1, flags=0001, data=41
119Feb 10 05:00:34 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Connected: Call disconnected, ts=-1, flags=0002, data=41
120Feb 10 05:00:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Call disconnected, ts=-1, flags=0002, data=41
121Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 12 (ACK received!): cid=38, did=39, tid=30, rid=0, sid=0, nid=0
122Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: RTP parameters, ts=1001, flags=0000, data=38
123Feb 10 05:00:34 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1001
124Feb 10 05:00:34 sip_ua[454]: conference.cpp:284: ---> ConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1001
125Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7297: --> ts 1001: 192.168.20.69[12002] --> 0.0.0.0[10006]
126Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7298: --> ts 1001: codec PCMA, VAD is on, red=0
127Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
128Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7401: routing 0.0.0.0 to 0.0.0.0 (lo)
129Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7413: getMac(0.0.0.0): 00:00:00:00:00:00
130Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:2750: 0.0.0.0: deleted from ARP hash
131Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7143: ts 1001: stopping RTP stream
132Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7143: ts 1001: stopping RTP stream
133Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: ACK received, ts=1001, flags=0000, data=38
134Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 9 (4xx received for Call!): cid=40, did=0, tid=31, rid=0, sid=0, nid=0
135Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1002]: Call disconnected, ts=1002, flags=0000, data=40
136Feb 10 05:00:34 sip_ua[454]: comcerto.cpp:7143: ts 1002: stopping RTP stream
137Feb 10 05:00:34 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1002
138Feb 10 05:00:34 sip_ua[454]: conference.cpp:769: ---> ConferenceParticipant[1001]: Call disconnected, ts=1001, flags=0000, data=-1
139Feb 10 05:00:34 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1001
140Feb 10 05:00:34 sip_ua[520]: repro.cpp:778: doSessionAccounting(): Session Ended 'branch=z9hG4bK2004159643'
141Feb 10 05:00:34 sip_ua[520]: repro.cpp:796: --> duration=0, [DS]tel:33@192.168.20.69: ---> []tel:123@192.168.20.69:
142Feb 10 05:00:34 sip_ua[520]: repro.cpp:1057: --> CDR: {"answer":1739163634,"duration":0,"hangup":1739163634,"org-host":"192.168.20.69","org-scheme":"tel","org-type":"DS","org-user":"33","to-host":"192.168.20.69","to-scheme":"tel","to-user":"123","type
143Feb 10 05:00:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=38, did=-1, tid=33, rid=0, sid=0, nid=0
144Feb 10 05:00:35 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
145Feb 10 05:00:35 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=36, did=-1, tid=0, rid=0, sid=0, nid=0
146Feb 10 05:00:35 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=16, did=0, tid=0, rid=0, sid=0, nid=0
147Feb 10 05:00:35 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=33, did=0, tid=0, rid=0, sid=0, nid=0
148Feb 10 05:00:37 sip_ua[454]: comcerto.cpp:7912: 0.0.0.0: host is down
149Feb 10 05:00:38 kernel: comcerto_wdt: closed unexpectedly. WDT will not stop!
150Jan 1 00:00:15 syslogd started: BusyBox v1.18.5
151Jan 1 00:00:15 kernel: klogd started: BusyBox v1.18.5 (2025-01-15 15:42:01 YEKT)
152Jan 1 00:00:15 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
153Jan 1 00:00:15 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
154Jan 1 00:00:15 kernel: Machine: Comcerto 300 (x570)
155Jan 1 00:00:15 kernel: Memory policy: ECC disabled, Data cache writeback
156Jan 1 00:00:15 kernel: On node 0 totalpages: 28416
157Jan 1 00:00:15 kernel: DMA zone: 222 pages used for memmap
158Jan 1 00:00:15 kernel: DMA zone: 0 pages reserved
159Jan 1 00:00:15 kernel: DMA zone: 28194 pages, LIFO batch:7
160Jan 1 00:00:15 kernel: Normal zone: 0 pages used for memmap
161Jan 1 00:00:15 kernel: CPU0: D VIPT write-back cache
162Jan 1 00:00:16 sysinit: brctl: SIOCGIFBR: Package not installed
163Jan 1 00:00:17 sysinit: sh: bad number
164Jan 1 00:00:17 sysinit: sh: bad number
165Jan 1 00:00:17 sysinit: sh: bad number
166Jan 1 00:00:17 sysinit: sh: bad number
167Jan 1 00:00:17 sysinit: sh: bad number
168Jan 1 00:00:17 sysinit: sh: bad number
169Jan 1 00:00:17 sysinit: sh: bad number
170Jan 1 00:00:17 sysinit: sh: bad number
171Jan 1 00:00:17 dnsmasq[376]: started, version 2.89 cachesize 150
172Jan 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
173Jan 1 00:00:17 dnsmasq[376]: using only locally-known addresses for lan
174Jan 1 00:00:17 dnsmasq[376]: no servers found in /tmp/resolv.conf.auto, will retry
175Jan 1 00:00:17 dnsmasq[376]: read /etc/hosts - 1 names
176Jan 1 00:00:19 kernel: NET: Registered protocol family 27
177Jan 1 00:00:19 dropbear[427]: Failed loading /etc/dropbear/dropbear_dss_host_key
178Jan 1 00:00:19 dropbear[428]: Running in background
179Jan 1 00:00:21 sysinit: sh: bad number
180Jan 1 00:00:21 sysinit: sh: bad number
181Jan 1 00:00:21 sysinit: sh: bad number
182Jan 1 00:00:21 sysinit: sh: bad number
183Jan 1 00:00:21 sysinit: sh: bad number
184Jan 1 00:00:21 sysinit: sh: bad number
185Jan 1 00:00:21 sysinit: sh: bad number
186Jan 1 00:00:21 sysinit: sh: bad number
187Jan 1 00:00:21 sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
188Jan 1 00:00:21 dnsmasq[443]: failed to create listening socket for port 53: Address already in use
189Jan 1 00:00:21 dnsmasq[443]: FAILED to start up
190Jan 1 00:00:22 sysinit: starting SIP UA daemon: sip_ua...net.unix.max_dgram_qlen = 2048
191Jan 1 00:00:22 sysinit: done.
192Jan 1 00:00:22 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
193Jan 1 00:00:22 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
194Jan 1 00:00:22 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
195Jan 1 00:00:22 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
196Jan 1 00:00:22 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
197Jan 1 00:00:28 kernel: eth1: no IPv6 routers present
198Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:554: starting sip_ua
199Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:555: main thread PID is 454
200Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
201Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: f579f7ec, computed: b612b212
202Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:137: Fw_env: cannot open /dev/mtd6: No such file or directory
203Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
204Jan 1 00:00:31 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: f579f7ec, computed: f579f7ec
205Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:568: serial number not found
206Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:586: Waiting for jffs2 scan finish and CPU idle time > 90%...
207Jan 1 00:00:31 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x0f48f800: read 0xe58fa0b7, calculated 0x1e6ef9f0.
208Jan 1 00:00:31 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x05b6b000: read 0x6d59c690, calculated 0x6f9db14e.
209Jan 1 00:00:32 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
210Jan 1 00:00:33 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
211Jan 1 00:00:34 sip_ua[454]: sip_ua.cpp:595: CPU idle 91%
212Jan 1 00:00:34 sip_ua[454]: scheduler.cpp:77: Initializing scheduling API
213Jan 1 00:00:34 sip_ua[454]: scheduler.cpp:107: Created schedule main scheduler
214Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5103: ==> SFKEYS: 0460 0305 bfec fef7
215Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5489: Comcerto C300 device: M82359 Rev.A
216Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5490: Comcerto C300 firmware version: v11_26_03_08_SS_04
217Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5491: Comcerto C300 SPU version: C64V_7_10_13
218Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5492: Device initialized successfully
219Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5499: TDM initialized successfully
220Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5382: Device mac address: 02:ad:c5:00:00:69
221Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5519: Channels available: 160 (G.711 20ms)
222Jan 1 00:00:35 sip_ua[458]: regSyncAgent.cpp:460: loading regDataBase from /lib/sip_ua/regDataBase.xml...
223Jan 1 00:00:36 sip_ua[458]: regSyncAgent.cpp:520: exception caught: ../rutil/ParseBuffer.hxx:230, Parse failed unexpected eof in context: ^
224Jan 1 00:00:36 sip_ua[458]: regSyncAgent.cpp:525: 0 object(s) loaded
225Jan 1 00:00:36 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
226Jan 1 00:00:36 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
227Jan 1 00:00:38 sip_ua[454]: poller.cpp:3404: firmware revision: 53
228Jan 1 00:00:38 sip_ua[477]: poller.cpp:2786: Configuring FPGA...
229Jan 1 00:00:38 sip_ua[477]: poller.cpp:2795: FPGA configured successfully
230Jan 1 00:00:38 sip_ua[477]: poller.cpp:2839: mofule 1: FS01 found
231Jan 1 00:00:38 sip_ua[477]: poller.cpp:2879: mofule 2: unknown module found (type=15)
232Jan 1 00:00:38 sip_ua[477]: poller.cpp:2895: board address is 7
233Jan 1 00:00:38 sip_ua[454]: mediaProxy.cpp:31: --> MediaProxy::start()
234Jan 1 00:00:44 sip_ua[477]: poller.cpp:2513: switching to CRC32 mode
235Jan 1 00:00:44 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
236Jan 1 00:00:44 sip_ua[477]: poller.cpp:890: ===> command globalconf received
237Jan 1 00:00:44 sip_ua[477]: comcerto.cpp:2217: !!!!! function IP_ADDRESS_v6 (0x0310): error CNF_ERROR_IP_ADDRESS_BAD_IP_ADDRESS (0x0b03):
238Jan 1 00:00:44 sip_ua[477]: comcerto.cpp:2218: Invalid IP Address
239Jan 1 00:00:44 sip_ua[477]: comcerto.cpp:5280: comcerto_set_ip6() failed (result=-2819)
240Jan 1 00:00:44 sip_ua[477]: poller.cpp:1431: downloading sound files from http://192.168.20.53/files/ve-01/sounds...
241Jan 1 00:00:44 dnsmasq[376]: reading /tmp/resolv.conf.auto
242Jan 1 00:00:44 dnsmasq[376]: using nameserver 8.8.8.8#53
243Jan 1 00:00:44 dnsmasq[376]: using only locally-known addresses for lan
244Feb 10 05:02:14 sip_ua[454]: sip_ua.cpp:875: IP change 127.0.0.1 --> 192.168.20.69 detected
245Feb 10 05:02:14 sip_ua[477]: poller.cpp:2429: duplicate request 39 (1047 ms)
246Feb 10 05:02:14 sip_ua[477]: poller.cpp:2429: duplicate request 43 (257 ms)
247Feb 10 05:02:14 sip_ua[477]: poller.cpp:2429: duplicate request 44 (265 ms)
248Feb 10 05:02:15 sip_ua[477]: fs01.cpp:136: module 1: FS01 created at base address 0x0400
249Feb 10 05:02:15 sip_ua[477]: fs01.cpp:333: module 1 [FS01]: cofidec Si3210 Revision E detected
250Feb 10 05:02:15 sip_ua[477]: poller.cpp:2429: duplicate request 45 (258 ms)
251Feb 10 05:02:15 sip_ua[477]: poller.cpp:2429: duplicate request 46 (270 ms)
252Feb 10 05:02:15 sip_ua[477]: poller.cpp:890: ===> command sslapply received
253Feb 10 05:02:15 sip_ua[477]: poller.cpp:890: ===> command userlist received
254Feb 10 05:02:16 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
255Feb 10 05:02:16 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
256Feb 10 05:02:16 sip_ua[477]: repro.cpp:1958: User directory updated
257Feb 10 05:02:16 sip_ua[477]: poller.cpp:2429: duplicate request 51 (1 ms)
258Feb 10 05:02:16 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Idle: Channel settings, ts=0, flags=0000, data=0
259Feb 10 05:02:16 sip_ua[477]: poller.cpp:890: ===> command ctime received
260Feb 10 05:02:16 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
261Feb 10 05:02:16 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Idle: IP address changed, ts=-1, flags=0001, data=1
262Feb 10 05:02:16 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0001, data=1
263Feb 10 05:02:16 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Idle: IP address changed, ts=-1, flags=0002, data=1
264Feb 10 05:02:16 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0002, data=1
265Feb 10 05:02:16 sip_ua[477]: poller.cpp:890: ===> command callgroups received
266Feb 10 05:02:16 sip_ua[477]: poller.cpp:890: ===> command routes received
267Feb 10 05:02:16 sip_ua[477]: poller.cpp:890: ===> command conferences received
268Feb 10 05:02:16 sip_ua[454]: pps.cpp:97: ---> ts=0, state=Idle: Channel settings, ts=-1, flags=0001, data=0
269Feb 10 05:02:16 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
270Feb 10 05:02:16 sip_ua[454]: virtualChannel.cpp:130: ---> VirtualChannelManager: conference '123' created
271Feb 10 05:02:16 sip_ua[454]: virtualChannel.cpp:139: ---> VirtualChannelManager: conference '123' found
272Feb 10 05:02:16 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
273Feb 10 05:02:16 sip_ua[454]: conference.cpp:284: ---> ConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
274Feb 10 05:02:16 sip_ua[477]: poller.cpp:890: ===> command registrations received
275Feb 10 05:02:16 sip_ua[477]: poller.cpp:1149: --> registrations updated.
276Feb 10 05:02:16 sip_ua[477]: fs01.cpp:376: module 1 [FS01]: DC-DC ON
277Feb 10 05:02:16 sip_ua[477]: fs01.cpp:390: module 1 [FS01]: DC-DC calibration started...
278Feb 10 05:02:16 sip_ua[477]: fs01.cpp:427: module 1 [FS01]: SLIC calibration started...
279Feb 10 05:02:17 sip_ua[477]: poller.cpp:3273: download complete
280Feb 10 05:02:19 sip_ua[477]: fs01.cpp:541: module 1 [FS01]: SLIC calibration 2 started...
281Feb 10 05:02:20 dropbear[529]: Child connection from 192.168.20.99:50521
282Feb 10 05:02:20 sip_ua[477]: fs01.cpp:626: module 1 [FS01]: init complete
283Feb 10 05:02:20 dropbear[529]: Pubkey auth succeeded for 'root' with ssh-rsa key SHA256:8i65UsekgFf6uAr6cmxT7M+qcX1JHrmsBe6AQj5aJ9c from 192.168.20.99:50521
284Feb 10 05:02:20 sip_ua[458]: regSyncAgent.cpp:842: connected to repro