Ticket #446: messages3

File messages3, 32.1 KB (added by roman_zhur, 8 days ago)
Line 
1Jan 14 09:07:29 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: Channel settings, ts=254, flags=0000, data=0
2Jan 14 09:07:29 sip_ua[477]: poller.cpp:890: ===> command globalconf received
3Jan 14 09:07:29 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
4Jan 14 09:07:29 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: Channel settings, ts=-1, flags=0001, data=0
5Jan 14 09:07:29 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
6Jan 14 09:07:29 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
7Jan 14 09:07:29 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0002, data=0
8Jan 14 09:07:29 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: Channel settings, ts=-1, flags=0002, data=0
9Jan 14 09:07:29 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
10Jan 14 09:07:29 sip_ua[477]: poller.cpp:890: ===> command userlist received
11Jan 14 09:07:29 sip_ua[477]: repro.cpp:1958: User directory updated
12Jan 14 09:07:29 sip_ua[477]: poller.cpp:2429: duplicate request 61 (82 ms)
13Jan 14 09:07:29 sip_ua[477]: poller.cpp:2429: duplicate request 60 (357 ms)
14Jan 14 09:07:30 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: Channel settings, ts=254, flags=0000, data=0
15Jan 14 09:07:30 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
16Jan 14 09:07:30 sip_ua[477]: poller.cpp:2429: duplicate request 63 (233 ms)
17Jan 14 09:07:30 sip_ua[477]: poller.cpp:2429: duplicate request 64 (242 ms)
18Jan 14 09:07:30 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
19Jan 14 09:07:30 sip_ua[477]: poller.cpp:890: ===> command sslapply received
20Jan 14 09:07:30 sip_ua[477]: poller.cpp:890: ===> command userlist received
21Jan 14 09:07:30 sip_ua[477]: repro.cpp:1958: User directory updated
22Jan 14 09:07:30 sip_ua[477]: poller.cpp:890: ===> command ctime received
23Jan 14 09:07:31 sip_ua[477]: poller.cpp:890: ===> command callgroups received
24Jan 14 09:07:31 sip_ua[477]: poller.cpp:890: ===> command routes received
25Jan 14 09:07:31 sip_ua[477]: poller.cpp:890: ===> command conferences received
26Jan 14 09:07:31 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
27Jan 14 09:07:31 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: Channel settings, ts=-1, flags=0001, data=0
28Jan 14 09:07:31 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
29Jan 14 09:07:31 sip_ua[454]: virtualChannel.cpp:139: ---> VirtualChannelManager: conference '00' found
30Jan 14 09:07:31 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
31Jan 14 09:07:31 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
32Jan 14 09:07:31 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: CAS event, ts=254, flags=0000, data=15
33Jan 14 09:07:32 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
34Jan 14 09:07:32 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=7
35Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=0
36Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
37Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
38Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
39Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
40Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
41Jan 14 09:07:33 sip_ua[454]: fxs.cpp:275: ts 254: dialing 022
42Jan 14 09:07:33 sip_ua[454]: user_agent.cpp:3892: --> ua_dial_out() <sip:disp@192.168.0.121> -> sip:022@192.168.0.121...
43Jan 14 09:07:33 sip_ua[523]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK1686121042'
44Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Tone detected, ts=254, flags=0000, data=255
45Jan 14 09:07:33 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=2, did=3, tid=2, rid=0, sid=0, nid=0
46Jan 14 09:07:33 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:022@127.0.0.1:6060;transport=udp (Call-ID: 2146147042@(null))
47Jan 14 09:07:33 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=2
48Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0001, data=2
49Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Incoming call, ts=-1, flags=0001, data=2
50Jan 14 09:07:33 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0001, data=2
51Jan 14 09:07:33 sip_ua[454]: virtualChannel.cpp:217: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=2
52Jan 14 09:07:33 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=2
53Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0002, data=2
54Jan 14 09:07:33 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Incoming call, ts=-1, flags=0002, data=2
55Jan 14 09:07:33 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0002, data=2
56Jan 14 09:07:33 sip_ua[454]: conference.cpp:135: --> 1002: 0x467ab8
57Jan 14 09:07:33 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: Incoming call, ts=1002, flags=0006, data=2
58Jan 14 09:07:33 sip_ua[454]: conference.cpp:552: --> checkpoint 2: '22@192.168.0.121'
59Jan 14 09:07:33 sip_ua[454]: conference.cpp:556: --> checkpoint 3a: 1002
60Jan 14 09:07:33 sip_ua[454]: conference.cpp:557: --> checkpoint 3b: 0x467ab8
61Jan 14 09:07:33 sip_ua[454]: conference.cpp:558: --> checkpoint 3c: 0x467ab8
62Jan 14 09:07:33 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: REFER received, ts=1003, flags=0000, data=0
63Jan 14 09:07:33 sip_ua[454]: user_agent.cpp:4329: Call 2 routed to TS 1002
64Jan 14 09:07:33 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: Call ID replaced, ts=1003, flags=0000, data=0
65Jan 14 09:07:33 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: RTP parameters, ts=1002, flags=0000, data=2
66Jan 14 09:07:33 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1002
67Jan 14 09:07:33 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1002
68Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7299: --> ts 1002: 192.168.0.121[12004] --> 192.168.0.121[10508]
69Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7300: --> ts 1002: codec PCMA, VAD is on, red=0
70Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7301: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
71Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7359: ts 1002: calling createConnection()
72Jan 14 09:07:33 sip_ua[523]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK1686121042'
73Jan 14 09:07:33 sip_ua[523]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK362576136'
74Jan 14 09:07:33 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=1, did=0, tid=1, rid=0, sid=0, nid=0
75Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7403: routing 192.168.0.121 to 192.168.0.121 (lo)
76Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7415: getMac(192.168.0.121): 02:ad:c5:00:02:c5
77Jan 14 09:07:33 sip_ua[454]: comcerto.cpp:7425: ts 1002: RTP destination is 02:ad:c5:00:02:c5
78Jan 14 09:07:33 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=1, did=5, tid=1, rid=0, sid=0, nid=0
79Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7145: ts 1002: stopping RTP stream
80Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7145: ts 1002: stopping RTP stream
81Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=4, did=0, tid=3, rid=0, sid=0, nid=0
82Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 12 (ACK received!): cid=2, did=3, tid=2, rid=0, sid=0, nid=0
83Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7124: ts 1002: starting RTP stream
84Jan 14 09:07:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=1
85Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7299: --> ts 254: 192.168.0.121[10508] --> 192.168.0.121[12004]
86Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7300: --> ts 254: codec PCMA, VAD is on, red=0
87Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7301: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
88Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7359: ts 254: calling createConnection()
89Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7403: routing 192.168.0.121 to 192.168.0.121 (lo)
90Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7415: getMac(192.168.0.121): 02:ad:c5:00:02:c5
91Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7425: ts 254: RTP destination is 02:ad:c5:00:02:c5
92Jan 14 09:07:34 sip_ua[523]: repro.cpp:958: doSessionAccounting(): Session Redirected
93Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7145: ts 254: stopping RTP stream
94Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 8 (Call is redirected!): cid=4, did=0, tid=3, rid=0, sid=0, nid=0
95Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7145: ts 254: stopping RTP stream
96Jan 14 09:07:34 sip_ua[523]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK1732931433'
97Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7124: ts 254: starting RTP stream
98Jan 14 09:07:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=1
99Jan 14 09:07:34 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: RTP parameters, ts=1002, flags=0000, data=2
100Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7299: --> ts 1002: 192.168.0.121[12004] --> 192.168.0.121[10508]
101Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7300: --> ts 1002: codec PCMA, VAD is on, red=0
102Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7301: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
103Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7403: routing 192.168.0.121 to 192.168.0.121 (lo)
104Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7415: getMac(192.168.0.121): 02:ad:c5:00:02:c5
105Jan 14 09:07:34 sip_ua[454]: comcerto.cpp:7425: ts 1002: RTP destination is 02:ad:c5:00:02:c5
106Jan 14 09:07:34 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: ACK received, ts=1002, flags=0000, data=2
107Jan 14 09:07:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: CAS event, ts=254, flags=0000, data=7
108Jan 14 09:07:34 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: Call redirected, ts=1003, flags=0000, data=4
109Jan 14 09:07:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
110Jan 14 09:07:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
111Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=4, did=0, tid=4, rid=0, sid=0, nid=0
112Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=4, did=6, tid=4, rid=0, sid=0, nid=0
113Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2499: ---> ringing with status code 180
114Jan 14 09:07:34 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: Remote is ringing, ts=1003, flags=0000, data=4
115Jan 14 09:07:34 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1003
116Jan 14 09:07:34 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1003
117Jan 14 09:07:34 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=4, did=6, tid=5, rid=0, sid=0, nid=0
118Jan 14 09:07:34 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: Message answered 2xx, ts=1003, flags=0000, data=4
119Jan 14 09:07:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: CAS event, ts=254, flags=0000, data=15
120Jan 14 09:07:36 sip_ua[454]: comcerto.cpp:7145: ts 254: stopping RTP stream
121Jan 14 09:07:36 sip_ua[523]: repro.cpp:778: doSessionAccounting(): Session Ended 'branch=z9hG4bK415822431'
122Jan 14 09:07:36 sip_ua[523]: repro.cpp:796: --> duration=3, [FS01]tel:disp@192.168.0.121: ---> []tel:022@192.168.0.121:
123Jan 14 09:07:36 sip_ua[523]: repro.cpp:1057: --> CDR: {"answer":1736845653,"duration":3,"hangup":1736845656,"org-host":"192.168.0.121","org-scheme":"tel","org-type":"FS01","org-user":"disp","to-host":"192.168.0.121","to-scheme":"tel","to-user":"022","
124Jan 14 09:07:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Call disconnected, ts=254, flags=0000, data=1
125Jan 14 09:07:36 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=2, did=3, tid=7, rid=0, sid=0, nid=0
126Jan 14 09:07:36 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=2, did=3, tid=7, rid=0, sid=0, nid=0
127Jan 14 09:07:36 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: Call disconnected, ts=1002, flags=0000, data=2
128Jan 14 09:07:36 sip_ua[454]: comcerto.cpp:7145: ts 1002: stopping RTP stream
129Jan 14 09:07:36 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1002
130Jan 14 09:07:36 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=1, did=-1, tid=6, rid=0, sid=0, nid=0
131Jan 14 09:07:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Message answered 2xx, ts=254, flags=0000, data=1
132Jan 14 09:07:41 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=1, did=0, tid=0, rid=0, sid=0, nid=0
133Jan 14 09:07:43 sip_ua[523]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK1732931433'
134Jan 14 09:07:43 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=4, did=6, tid=4, rid=0, sid=0, nid=0
135Jan 14 09:07:43 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1003]: RTP parameters, ts=1003, flags=0000, data=4
136Jan 14 09:07:43 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1003
137Jan 14 09:07:43 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1003
138Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7299: --> ts 1003: 192.168.0.121[12006] --> 192.168.0.133[10002]
139Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7300: --> ts 1003: codec PCMA, VAD is on, red=0
140Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7301: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
141Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7359: ts 1003: calling createConnection()
142Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7403: routing 192.168.0.133 to 192.168.0.133 (eth0)
143Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7415: getMac(192.168.0.133): 02:ad:c3:00:00:06
144Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7425: ts 1001: RTP destination is 02:ad:c3:00:00:06
145Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7145: ts 1001: stopping RTP stream
146Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7145: ts 1001: stopping RTP stream
147Jan 14 09:07:43 sip_ua[454]: comcerto.cpp:7124: ts 1001: starting RTP stream
148Jan 14 09:07:43 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1001]: Call answered, ts=1003, flags=0000, data=4
149Jan 14 09:07:43 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1001
150Jan 14 09:07:43 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Participant status changed, ts=2000, flags=0000, data=1001
151Jan 14 09:07:43 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
152Jan 14 09:07:43 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
153Jan 14 09:07:44 sip_ua[523]: repro.cpp:778: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---83ff0d586a410b73'
154Jan 14 09:07:44 sip_ua[523]: repro.cpp:796: --> duration=1, [Conf]tel:00@192.168.0.121: ---> [FXS]sip:op1@192.168.0.133:
155Jan 14 09:07:44 sip_ua[523]: repro.cpp:1057: --> CDR: {"answer":1736845663,"duration":1,"hangup":1736845664,"org-host":"192.168.0.121","org-scheme":"tel","org-type":"Conf","org-user":"00","to-host":"192.168.0.133","to-scheme":"sip","to-type":"FXS","to
156Jan 14 09:07:44 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=4, did=6, tid=8, rid=0, sid=0, nid=0
157Jan 14 09:07:44 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=4, did=6, tid=8, rid=0, sid=0, nid=0
158Jan 14 09:07:44 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1001]: Call disconnected, ts=1003, flags=0000, data=4
159Jan 14 09:07:44 sip_ua[454]: comcerto.cpp:7145: ts 1001: stopping RTP stream
160Jan 14 09:07:44 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Participant left the conference, ts=2000, flags=0000, data=1001
161Jan 14 09:07:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
162Jan 14 09:07:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=7
163Jan 14 09:07:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=0
164Jan 14 09:07:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
165Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
166Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
167Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
168Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
169Jan 14 09:07:46 sip_ua[454]: fxs.cpp:275: ts 254: dialing 022
170Jan 14 09:07:46 sip_ua[454]: user_agent.cpp:3892: --> ua_dial_out() <sip:disp@192.168.0.121> -> sip:022@192.168.0.121...
171Jan 14 09:07:46 sip_ua[523]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK2047338342'
172Jan 14 09:07:46 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
173Jan 14 09:07:46 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:022@127.0.0.1:6060;transport=udp (Call-ID: 441487@(null))
174Jan 14 09:07:46 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=8
175Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0001, data=8
176Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Incoming call, ts=-1, flags=0001, data=8
177Jan 14 09:07:46 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0001, data=8
178Jan 14 09:07:46 sip_ua[454]: virtualChannel.cpp:217: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=8
179Jan 14 09:07:46 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=8
180Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0002, data=8
181Jan 14 09:07:46 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Incoming call, ts=-1, flags=0002, data=8
182Jan 14 09:07:46 sip_ua[454]: conference.cpp:96: ---> ConferenceFocus: Incoming call, ts=-1, flags=0002, data=8
183Jan 14 09:07:46 sip_ua[454]: conference.cpp:135: --> 1002: 0x4d2638
184Jan 14 09:07:46 sip_ua[454]: conference.cpp:748: ---> ConferenceParticipant[1002]: Incoming call, ts=1002, flags=0006, data=8
185Jan 14 09:07:46 sip_ua[454]: conference.cpp:552: --> checkpoint 2: '22@192.168.0.121'
186Jan 14 09:07:46 kernel: comcerto_wdt: closed unexpectedly. WDT will not stop!
187Jan 1 00:00:13 syslogd started: BusyBox v1.18.5
188Jan 1 00:00:13 kernel: klogd started: BusyBox v1.18.5 (2024-08-18 17:59:54 YEKT)
189Jan 1 00:00:13 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
190Jan 1 00:00:13 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
191Jan 1 00:00:13 kernel: Machine: Comcerto 300 (x570)
192Jan 1 00:00:13 kernel: Memory policy: ECC disabled, Data cache writeback
193Jan 1 00:00:13 kernel: On node 0 totalpages: 28416
194Jan 1 00:00:13 kernel: DMA zone: 222 pages used for memmap
195Jan 1 00:00:13 kernel: DMA zone: 0 pages reserved
196Jan 1 00:00:13 kernel: DMA zone: 28194 pages, LIFO batch:7
197Jan 1 00:00:13 kernel: Normal zone: 0 pages used for memmap
198Jan 1 00:00:13 kernel: CPU0: D VIPT write-back cache
199Jan 1 00:00:13 kernel: 3 cmdlinepart partitions found on MTD device gen_nand.0
200Jan 1 00:00:13 kernel: Creating 3 MTD partitions on "gen_nand.0":
201Jan 1 00:00:13 kernel: 0x00000000-0x00200000 : "csp"
202Jan 1 00:00:13 kernel: 0x00200000-0x00600000 : "msp"
203Jan 1 00:00:13 kernel: 0x00600000-0x10000000 : "fs"
204Jan 1 00:00:13 kernel: physmap platform flash device: 00080000 at b0000000
205Jan 1 00:00:13 kernel: CFI: Found no physmap-flash.1 device at location zero
206Jan 1 00:00:13 kernel: Found: ST M29W040B
207Jan 1 00:00:15 sysinit: brctl: SIOCGIFBR: Package not installed
208Jan 1 00:00:15 sysinit: sh: bad number
209Jan 1 00:00:15 sysinit: sh: bad number
210Jan 1 00:00:15 sysinit: sh: bad number
211Jan 1 00:00:15 sysinit: sh: bad number
212Jan 1 00:00:15 sysinit: sh: bad number
213Jan 1 00:00:15 sysinit: sh: bad number
214Jan 1 00:00:15 sysinit: sh: bad number
215Jan 1 00:00:15 sysinit: sh: bad number
216Jan 1 00:00:16 dnsmasq[369]: started, version 2.89 cachesize 150
217Jan 1 00:00:16 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
218Jan 1 00:00:16 dnsmasq[369]: using only locally-known addresses for lan
219Jan 1 00:00:16 dnsmasq[369]: no servers found in /tmp/resolv.conf.auto, will retry
220Jan 1 00:00:16 dnsmasq[369]: read /etc/hosts - 1 names
221Jan 1 00:00:17 kernel: NET: Registered protocol family 27
222Jan 1 00:00:18 dropbear[427]: Failed loading /etc/dropbear/dropbear_dss_host_key
223Jan 1 00:00:18 dropbear[428]: Running in background
224Jan 1 00:00:18 kernel: JFFS2 notice: (432) check_node_data: wrong data CRC in data node at 0x0d15d000: read 0xa6968e8e, calculated 0x453a9fd4.
225Jan 1 00:00:20 sysinit: sh: bad number
226Jan 1 00:00:20 sysinit: sh: bad number
227Jan 1 00:00:20 sysinit: sh: bad number
228Jan 1 00:00:20 sysinit: sh: bad number
229Jan 1 00:00:20 sysinit: sh: bad number
230Jan 1 00:00:20 sysinit: sh: bad number
231Jan 1 00:00:20 sysinit: sh: bad number
232Jan 1 00:00:20 sysinit: sh: bad number
233Jan 1 00:00:20 sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
234Jan 1 00:00:20 dnsmasq[443]: failed to create listening socket for port 53: Address already in use
235Jan 1 00:00:20 dnsmasq[443]: FAILED to start up
236Jan 1 00:00:21 sysinit: starting SIP UA daemon: sip_ua...net.unix.max_dgram_qlen = 2048
237Jan 1 00:00:21 sysinit: done.
238Jan 1 00:00:21 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
239Jan 1 00:00:21 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
240Jan 1 00:00:21 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
241Jan 1 00:00:21 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
242Jan 1 00:00:21 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
243Jan 1 00:00:26 kernel: eth1: no IPv6 routers present
244Jan 1 00:00:29 sip_ua[454]: sip_ua.cpp:554: starting sip_ua
245Jan 1 00:00:29 sip_ua[454]: sip_ua.cpp:555: main thread PID is 454
246Jan 1 00:00:29 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
247Jan 1 00:00:29 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: d211d87d, computed: 917a9d83
248Jan 1 00:00:29 sip_ua[454]: fw-env.cpp:137: Fw_env: cannot open /dev/mtd6: No such file or directory
249Jan 1 00:00:29 sip_ua[454]: fw-env.cpp:52: Fw_env: /dev/mtd5: partition size is 65536
250Jan 1 00:00:29 sip_ua[454]: fw-env.cpp:76: Fw_env: /dev/mtd5: CRC32: readed: d211d87d, computed: d211d87d
251Jan 1 00:00:29 sip_ua[454]: sip_ua.cpp:570: serial number: G04454
252Jan 1 00:00:29 sip_ua[454]: sip_ua.cpp:586: Waiting for jffs2 scan finish and CPU idle time > 90%...
253Jan 1 00:00:29 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x0d144000: read 0x8082dcef, calculated 0x9d424348.
254Jan 1 00:00:30 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
255Jan 1 00:00:31 kernel: JFFS2 notice: (253) check_node_data: wrong data CRC in data node at 0x0935b000: read 0x5ec1f9cb, calculated 0xde79359d.
256Jan 1 00:00:31 sip_ua[454]: sip_ua.cpp:595: CPU idle 0%
257Jan 1 00:00:32 sip_ua[454]: sip_ua.cpp:595: CPU idle 54%
258Jan 1 00:00:33 sip_ua[454]: sip_ua.cpp:595: CPU idle 100%
259Jan 1 00:00:33 sip_ua[454]: scheduler.cpp:77: Initializing scheduling API
260Jan 1 00:00:33 sip_ua[454]: scheduler.cpp:107: Created schedule main scheduler
261Jan 1 00:00:33 sip_ua[454]: comcerto.cpp:5105: ==> SFKEYS: 0460 0305 bfec fef7
262Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5491: Comcerto C300 device: M82359 Rev.A
263Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5492: Comcerto C300 firmware version: v11_26_03_08_SS_04
264Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5493: Comcerto C300 SPU version: C64V_7_10_13
265Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5494: Device initialized successfully
266Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5501: TDM initialized successfully
267Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5384: Device mac address: 02:ad:c5:00:02:c5
268Jan 1 00:00:34 sip_ua[454]: comcerto.cpp:5521: Channels available: 160 (G.711 20ms)
269Jan 1 00:00:34 sip_ua[458]: regSyncAgent.cpp:460: loading regDataBase from /lib/sip_ua/regDataBase.xml...
270Jan 1 00:00:34 sip_ua[458]: regSyncAgent.cpp:489: file timestamp is 1736845274 sec in the future
271Jan 14 09:01:48 sip_ua[458]: regSyncAgent.cpp:525: 2 object(s) loaded
272Jan 14 09:01:48 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
273Jan 14 09:01:48 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
274Jan 14 09:01:50 sip_ua[454]: poller.cpp:3404: firmware revision: 52
275Jan 14 09:01:50 sip_ua[477]: poller.cpp:2786: Configuring FPGA...
276Jan 14 09:01:50 sip_ua[477]: poller.cpp:2795: FPGA configured successfully
277Jan 14 09:01:50 sip_ua[477]: poller.cpp:2839: mofule 1: FS01 found
278Jan 14 09:01:50 sip_ua[477]: poller.cpp:2867: mofule 2: FS01 found
279Jan 14 09:01:50 sip_ua[477]: poller.cpp:2895: board address is 1
280Jan 14 09:01:50 sip_ua[454]: mediaProxy.cpp:31: --> MediaProxy::start()
281Jan 14 09:01:56 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
282Jan 14 09:01:56 sip_ua[477]: poller.cpp:2513: switching to CRC32 mode
283Jan 14 09:01:57 sip_ua[477]: poller.cpp:890: ===> command globalconf received
284Jan 14 09:01:57 dnsmasq[369]: reading /tmp/resolv.conf.auto
285Jan 14 09:01:57 dnsmasq[369]: using nameserver 8.8.8.8#53
286Jan 14 09:01:57 dnsmasq[369]: using only locally-known addresses for lan
287Jan 14 09:01:57 sip_ua[477]: comcerto.cpp:2217: !!!!! function IP_ADDRESS_v6 (0x0310): error CNF_ERROR_IP_ADDRESS_BAD_IP_ADDRESS (0x0b03):
288Jan 14 09:01:57 sip_ua[477]: comcerto.cpp:2218: Invalid IP Address
289Jan 14 09:01:57 sip_ua[477]: comcerto.cpp:5282: comcerto_set_ip6() failed (result=-2819)
290Jan 14 09:01:57 sip_ua[477]: poller.cpp:1431: downloading sound files from http://192.168.1.104/files/ve-01/sounds...
291Jan 14 09:01:57 sip_ua[454]: sip_ua.cpp:875: IP change 127.0.0.1 --> 192.168.0.121 detected
292Jan 14 09:01:58 sip_ua[477]: poller.cpp:2429: duplicate request 120 (361 ms)
293Jan 14 09:01:58 sip_ua[477]: poller.cpp:2429: duplicate request 122 (376 ms)
294Jan 14 09:09:24 sip_ua[477]: fs01.cpp:136: module 1: FS01 created at base address 0x0400
295Jan 14 09:09:24 sip_ua[477]: poller.cpp:2429: duplicate request 123 (250 ms)
296Jan 14 09:09:24 sip_ua[477]: poller.cpp:2429: duplicate request 124 (260 ms)
297Jan 14 09:09:24 sip_ua[477]: fs01.cpp:333: module 1 [FS01]: cofidec Si3210 Revision E detected
298Jan 14 09:09:24 sip_ua[477]: fs01.cpp:136: module 2: FS01 created at base address 0x0500
299Jan 14 09:09:24 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
300Jan 14 09:09:24 sip_ua[454]: repro.cpp:1544: --> skipping secure transport: no cert file
301Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
302Jan 14 09:09:24 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
303Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
304Jan 14 09:09:24 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: IP address changed, ts=-1, flags=0001, data=1
305Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0001, data=1
306Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0001, data=1
307Jan 14 09:09:24 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: IP address changed, ts=-1, flags=0002, data=1
308Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0002, data=1
309Jan 14 09:09:24 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0002, data=1
310Jan 14 09:09:25 sip_ua[477]: fs01.cpp:333: module 2 [FS01]: cofidec Si3210 Revision E detected
311Jan 14 09:09:25 sip_ua[477]: fs01.cpp:376: module 1 [FS01]: DC-DC ON
312Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command sslapply received
313Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command userlist received
314Jan 14 09:09:25 sip_ua[477]: repro.cpp:1958: User directory updated
315Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command ctime received
316Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command callgroups received
317Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command routes received
318Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command conferences received
319Jan 14 09:09:25 sip_ua[454]: pps.cpp:97: ---> ts=2, state=Idle: Channel settings, ts=-1, flags=0001, data=0
320Jan 14 09:09:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
321Jan 14 09:09:25 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
322Jan 14 09:09:25 sip_ua[454]: virtualChannel.cpp:130: ---> VirtualChannelManager: conference '00' created
323Jan 14 09:09:25 sip_ua[454]: virtualChannel.cpp:139: ---> VirtualChannelManager: conference '00' found
324Jan 14 09:09:25 sip_ua[454]: conference.cpp:653: ---> StaticConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
325Jan 14 09:09:25 sip_ua[454]: conference.cpp:285: ---> ConferenceFocus: Channel settings, ts=2000, flags=0000, data=0
326Jan 14 09:09:25 sip_ua[477]: fs01.cpp:390: module 1 [FS01]: DC-DC calibration started...
327Jan 14 09:09:25 sip_ua[477]: fs01.cpp:427: module 1 [FS01]: SLIC calibration started...
328Jan 14 09:09:25 sip_ua[477]: poller.cpp:890: ===> command registrations received
329Jan 14 09:09:25 sip_ua[477]: poller.cpp:1149: --> registrations updated.
330Jan 14 09:09:25 sip_ua[477]: fs01.cpp:376: module 2 [FS01]: DC-DC ON
331Jan 14 09:09:26 sip_ua[477]: fs01.cpp:390: module 2 [FS01]: DC-DC calibration started...
332Jan 14 09:09:26 sip_ua[477]: fs01.cpp:427: module 2 [FS01]: SLIC calibration started...
333Jan 14 09:09:27 sip_ua[477]: poller.cpp:3273: download complete
334Jan 14 09:09:29 sip_ua[477]: fs01.cpp:541: module 1 [FS01]: SLIC calibration 2 started...
335Jan 14 09:09:29 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
336Jan 14 09:09:29 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=7
337Jan 14 09:09:29 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
338Jan 14 09:09:29 sip_ua[477]: fs01.cpp:626: module 1 [FS01]: init complete
339Jan 14 09:09:29 sip_ua[477]: fs01.cpp:541: module 2 [FS01]: SLIC calibration 2 started...
340Jan 14 09:09:30 sip_ua[477]: fs01.cpp:626: module 2 [FS01]: init complete
341Jan 14 09:09:31 dropbear[529]: Child connection from 192.168.0.142:50900
342Jan 14 09:09:32 kernel: eth2: no IPv6 routers present
343Jan 14 09:09:32 dropbear[529]: Pubkey auth succeeded for 'root' with ssh-rsa key SHA256:8i65UsekgFf6uAr6cmxT7M+qcX1JHrmsBe6AQj5aJ9c from 192.168.0.142:50900