Ticket #456: messages_ve01_2

File messages_ve01_2, 22.5 KB (added by roman_zhur, 3 weeks ago)
Line 
1Apr 27 09:40:59 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=28
2Apr 27 09:40:59 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=255
3Apr 27 09:40:59 sip_ua[391]: sl.cpp:348: SL[2]: duration 90 ms
4Apr 27 09:41:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=28
5Apr 27 09:41:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=255
6Apr 27 09:41:00 sip_ua[391]: sl.cpp:348: SL[2]: duration 50 ms
7Apr 27 09:41:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone completed, ts=2, flags=0000, data=3
8Apr 27 09:41:00 sip_ua[391]: sl.cpp:187: ts 02: digit '1'
9Apr 27 09:41:01 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=28
10Apr 27 09:41:01 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=255
11Apr 27 09:41:01 sip_ua[391]: sl.cpp:348: SL[2]: duration 50 ms
12Apr 27 09:41:01 sip_ua[391]: sl.cpp:187: ts 02: digit '1'
13Apr 27 09:41:01 sip_ua[391]: sl.cpp:81: ts 2: dialing 11
14Apr 27 09:41:01 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out() -> sip:11@192.168.20.69...
15Apr 27 09:41:01 sip_ua[454]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK789987066'
16Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=10, did=11, tid=14, rid=0, sid=0, nid=0
17Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2272: INVITE received: sip:11@127.0.0.1:6060;transport=udp (Call-ID: 398813729@(null))
18Apr 27 09:41:01 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=10
19Apr 27 09:41:01 sip_ua[391]: fxs.cpp:1020: channel 1: ringing
20Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=9, did=12, tid=13, rid=0, sid=0, nid=0
21Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2499: ---> ringing with status code 180
22Apr 27 09:41:01 sip_ua[391]: user_agent.cpp:4329: Call 10 routed to TS 1
23Apr 27 09:41:01 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Remote is ringing, ts=2, flags=0000, data=9
24Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=10, did=11, tid=16, rid=0, sid=0, nid=0
25Apr 27 09:41:01 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=9, did=12, tid=15, rid=0, sid=0, nid=0
26Apr 27 09:41:01 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Message answered 2xx, ts=2, flags=0000, data=9
27Apr 27 09:41:04 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Ringing: CAS event, ts=1, flags=0000, data=7
28Apr 27 09:41:04 sip_ua[391]: comcerto.cpp:6311: channel 1: answer
29Apr 27 09:41:04 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP parameters, ts=1, flags=0000, data=10
30Apr 27 09:41:04 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
31Apr 27 09:41:04 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
32Apr 27 09:41:04 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
33Apr 27 09:41:04 sip_ua[391]: comcerto.cpp:7207: ts 1: calling createConnection()
34Apr 27 09:41:04 sip_ua[454]: repro.cpp:832: doSessionAccounting(): Session Established 'branch=z9hG4bK789987066'
35Apr 27 09:41:04 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=9, did=12, tid=13, rid=0, sid=0, nid=0
36Apr 27 09:41:05 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 12 (ACK received!): cid=10, did=11, tid=14, rid=0, sid=0, nid=0
37Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
38Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
39Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
40Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:6972: ts 1: starting RTP stream
41Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: RTP parameters, ts=2, flags=0000, data=9
42Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7147: --> ts 2: 192.168.20.69[10004] --> 192.168.20.69[10002]
43Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7148: --> ts 2: codec PCMA, VAD is on, red=0
44Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
45Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7207: ts 2: calling createConnection()
46Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
47Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
48Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7269: ts 2: RTP destination is 02:ad:c3:00:00:06
49Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:6972: ts 2: starting RTP stream
50Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Call answered, ts=2, flags=0000, data=9
51Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP parameters, ts=1, flags=0000, data=10
52Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
53Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
54Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
55Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
56Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
57Apr 27 09:41:05 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
58Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: ACK received, ts=1, flags=0000, data=10
59Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: CAS event, ts=1, flags=0000, data=7
60Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
61Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
62Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
63Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
64Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
65Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
66Apr 27 09:41:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone completed, ts=2, flags=0000, data=0
67Apr 27 09:41:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=0
68Apr 27 09:41:06 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
69Apr 27 09:41:10 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
70Apr 27 09:41:10 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
71Apr 27 09:41:10 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
72Apr 27 09:41:10 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
73Apr 27 09:41:15 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
74Apr 27 09:41:15 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
75Apr 27 09:41:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
76Apr 27 09:41:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
77Apr 27 09:41:20 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
78Apr 27 09:41:20 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
79Apr 27 09:41:20 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
80Apr 27 09:41:20 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
81Apr 27 09:41:25 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
82Apr 27 09:41:25 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
83Apr 27 09:41:25 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
84Apr 27 09:41:25 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
85Apr 27 09:41:27 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=28
86Apr 27 09:41:27 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=18
87Apr 27 09:41:27 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: PassThru mode autoswitch, ts=2, flags=0000, data=1
88Apr 27 09:41:27 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 1
89Apr 27 09:41:27 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=255
90Apr 27 09:41:27 sip_ua[391]: sl.cpp:348: SL[2]: duration 150 ms
91Apr 27 09:41:27 sip_ua[391]: sl.cpp:391: SL[2]: unexpected short pulse received in state Connected
92Apr 27 09:41:27 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: PassThru mode autoswitch, ts=1, flags=0000, data=2
93Apr 27 09:41:27 sip_ua[391]: comcerto.cpp:5982: ts 1: PassThru mode autoswitch to 2
94Apr 27 09:41:27 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
95Apr 27 09:41:27 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
96Apr 27 09:41:28 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=16
97Apr 27 09:41:28 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=255
98Apr 27 09:41:30 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
99Apr 27 09:41:30 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
100Apr 27 09:41:30 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
101Apr 27 09:41:30 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
102Apr 27 09:41:35 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
103Apr 27 09:41:35 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
104Apr 27 09:41:35 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
105Apr 27 09:41:35 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
106Apr 27 09:41:40 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
107Apr 27 09:41:40 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
108Apr 27 09:41:40 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
109Apr 27 09:41:40 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
110Apr 27 09:41:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=16
111Apr 27 09:41:44 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=255
112Apr 27 09:41:44 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: TDM Call Progress detected, ts=1, flags=0000, data=81
113Apr 27 09:41:44 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: PassThru mode autoswitch, ts=1, flags=0000, data=0
114Apr 27 09:41:44 sip_ua[391]: comcerto.cpp:5982: ts 1: PassThru mode autoswitch to 0
115Apr 27 09:41:44 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
116Apr 27 09:41:44 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: PassThru mode autoswitch, ts=2, flags=0000, data=0
117Apr 27 09:41:44 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 0
118Apr 27 09:41:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
119Apr 27 09:41:45 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
120Apr 27 09:41:45 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
121Apr 27 09:41:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
122Apr 27 09:41:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
123Apr 27 09:41:45 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=0
124Apr 27 09:41:50 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
125Apr 27 09:41:50 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
126Apr 27 09:41:50 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
127Apr 27 09:41:50 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
128Apr 27 09:41:55 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
129Apr 27 09:41:55 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
130Apr 27 09:41:55 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
131Apr 27 09:41:55 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
132Apr 27 09:42:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
133Apr 27 09:42:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
134Apr 27 09:42:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
135Apr 27 09:42:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
136Apr 27 09:42:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
137Apr 27 09:42:05 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
138Apr 27 09:42:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
139Apr 27 09:42:05 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
140Apr 27 09:42:10 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
141Apr 27 09:42:10 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
142Apr 27 09:42:10 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
143Apr 27 09:42:10 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
144Apr 27 09:42:15 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
145Apr 27 09:42:15 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
146Apr 27 09:42:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
147Apr 27 09:42:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
148Apr 27 09:42:20 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
149Apr 27 09:42:20 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
150Apr 27 09:42:20 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
151Apr 27 09:42:20 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
152Apr 27 09:42:25 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
153Apr 27 09:42:25 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
154Apr 27 09:42:25 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
155Apr 27 09:42:25 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
156Apr 27 09:42:30 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
157Apr 27 09:42:30 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
158Apr 27 09:42:30 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
159Apr 27 09:42:30 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
160Apr 27 09:42:35 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
161Apr 27 09:42:35 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
162Apr 27 09:42:35 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
163Apr 27 09:42:35 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
164Apr 27 09:42:40 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
165Apr 27 09:42:40 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
166Apr 27 09:42:40 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
167Apr 27 09:42:40 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
168Apr 27 09:42:45 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
169Apr 27 09:42:45 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
170Apr 27 09:42:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
171Apr 27 09:42:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
172Apr 27 09:42:50 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
173Apr 27 09:42:50 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
174Apr 27 09:42:50 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
175Apr 27 09:42:50 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
176Apr 27 09:42:55 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
177Apr 27 09:42:55 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
178Apr 27 09:42:55 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
179Apr 27 09:42:55 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
180Apr 27 09:43:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
181Apr 27 09:43:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
182Apr 27 09:43:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
183Apr 27 09:43:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: CAS event, ts=1, flags=0000, data=15
184Apr 27 09:43:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
185Apr 27 09:43:00 sip_ua[391]: comcerto.cpp:6993: ts 1: stopping RTP stream
186Apr 27 09:43:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Call disconnected, ts=1, flags=0000, data=10
187Apr 27 09:43:00 sip_ua[454]: repro.cpp:742: doSessionAccounting(): Session Ended 'branch=z9hG4bK1957898930'
188Apr 27 09:43:00 sip_ua[454]: repro.cpp:760: --> duration=116, [SL]tel:ve01@192.168.20.69: ---> [FXS]sip:11@192.168.20.69:
189Apr 27 09:43:00 sip_ua[454]: repro.cpp:1021: --> CDR: {"answer":1745746864,"duration":116,"hangup":1745746980,"org-host":"192.168.20.69","org-scheme":"tel","org-type":"SL","org-user":"ve01","to-host":"192.168.20.69","to-scheme":"sip","to-type":"FXS","
190Apr 27 09:43:00 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=9, did=12, tid=18, rid=0, sid=0, nid=0
191Apr 27 09:43:00 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=9, did=12, tid=18, rid=0, sid=0, nid=0
192Apr 27 09:43:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Call disconnected, ts=2, flags=0000, data=9
193Apr 27 09:43:00 sip_ua[391]: comcerto.cpp:6993: ts 2: stopping RTP stream
194Apr 27 09:43:00 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=10, did=-1, tid=17, rid=0, sid=0, nid=0
195Apr 27 09:43:00 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Message answered 2xx, ts=1, flags=0000, data=10
196Apr 27 09:43:00 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: RTP monitor, ts=2, flags=0000, data=0
197Apr 27 09:43:01 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone completed, ts=2, flags=0000, data=0
198Apr 27 09:43:05 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=10, did=0, tid=0, rid=0, sid=0, nid=0
199Apr 27 09:43:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=28
200Apr 27 09:43:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=18
201Apr 27 09:43:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: PassThru mode autoswitch, ts=2, flags=0000, data=1
202Apr 27 09:43:15 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 1
203Apr 27 09:43:15 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=255
204Apr 27 09:43:15 sip_ua[391]: sl.cpp:348: SL[2]: duration 170 ms
205Apr 27 09:43:30 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone completed, ts=2, flags=0000, data=5
206Apr 27 09:43:31 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone completed, ts=2, flags=0000, data=0
207Apr 27 09:43:32 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: TDM Call Progress detected, ts=2, flags=0000, data=81
208Apr 27 09:43:32 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: PassThru mode autoswitch, ts=2, flags=0000, data=0
209Apr 27 09:43:32 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 0
210Apr 27 09:43:32 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=9, did=0, tid=0, rid=0, sid=0, nid=0