Ticket #455: messages_VE-01

File messages_VE-01, 12.9 KB (added by roman_zhur, 40 hours ago)
Line 
1May 13 10:35:42 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: CAS event, ts=1, flags=0000, data=7
2May 13 10:35:42 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Dialtone: CAS event, ts=1, flags=0000, data=7
3May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Dialtone: DTMF detected, ts=1, flags=0000, data=2
4May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Dialing: Tone completed, ts=1, flags=0000, data=3
5May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Dialing: Tone detected, ts=1, flags=0000, data=255
6May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Dialing: DTMF detected, ts=1, flags=0000, data=2
7May 13 10:35:43 sip_ua[391]: fxs.cpp:272: ts 1: dialing 22
8May 13 10:35:43 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out() <sip:11@192.168.20.69> -> sip:22@192.168.20.69...
9May 13 10:35:43 sip_ua[449]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK402693463'
10May 13 10:35:43 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
11May 13 10:35:43 sip_ua[416]: user_agent.cpp:2272: INVITE received: sip:22@127.0.0.1:6060;transport=udp (Call-ID: 1140624338@(null))
12May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Incoming call, ts=-1, flags=0001, data=10
13May 13 10:35:43 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=10
14May 13 10:35:43 sip_ua[391]: virtualChannel.cpp:197: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=10
15May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Incoming call, ts=-1, flags=0002, data=10
16May 13 10:35:43 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=10
17May 13 10:35:43 sip_ua[391]: user_agent.cpp:4329: Call 10 routed to TS 2
18May 13 10:35:43 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Tone detected, ts=1, flags=0000, data=255
19May 13 10:35:43 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=9, did=0, tid=13, rid=0, sid=0, nid=0
20May 13 10:35:43 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
21May 13 10:35:44 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
22May 13 10:35:45 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
23May 13 10:35:46 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: RTP parameters, ts=2, flags=0000, data=10
24May 13 10:35:46 sip_ua[391]: comcerto.cpp:7147: --> ts 2: 192.168.20.69[10004] --> 192.168.20.69[10002]
25May 13 10:35:46 sip_ua[391]: comcerto.cpp:7148: --> ts 2: codec PCMA, VAD is on, red=0
26May 13 10:35:46 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
27May 13 10:35:46 sip_ua[391]: comcerto.cpp:7207: ts 2: calling createConnection()
28May 13 10:35:46 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
29May 13 10:35:46 sip_ua[416]: user_agent.cpp:2499: ---> ringing with status code 183
30May 13 10:35:46 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
31May 13 10:35:46 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
32May 13 10:35:46 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
33May 13 10:35:46 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
34May 13 10:35:46 sip_ua[391]: comcerto.cpp:7269: ts 2: RTP destination is 02:ad:c3:00:00:06
35May 13 10:35:46 sip_ua[391]: comcerto.cpp:6972: ts 2: starting RTP stream
36May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: RTP parameters, ts=1, flags=0000, data=9
37May 13 10:35:46 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
38May 13 10:35:46 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
39May 13 10:35:46 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
40May 13 10:35:46 sip_ua[391]: comcerto.cpp:7207: ts 1: calling createConnection()
41May 13 10:35:46 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
42May 13 10:35:46 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
43May 13 10:35:46 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
44May 13 10:35:46 sip_ua[391]: comcerto.cpp:6972: ts 1: starting RTP stream
45May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Remote is ringing, ts=1, flags=0000, data=9
46May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Message answered 2xx, ts=1, flags=0000, data=9
47May 13 10:35:46 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
48May 13 10:35:46 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
49May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: CAS event, ts=1, flags=0000, data=7
50May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=1
51May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Statistics received, ts=1, flags=0000, data=0
52May 13 10:35:46 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Statistics received, ts=1, flags=0000, data=0
53May 13 10:35:46 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=1
54May 13 10:35:47 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=0
55May 13 10:35:47 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=1
56May 13 10:35:48 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=0
57May 13 10:35:48 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: Tone detected, ts=2, flags=0000, data=28
58May 13 10:35:48 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=1
59May 13 10:35:48 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Dialed: Tone detected, ts=2, flags=0000, data=255
60May 13 10:35:48 sip_ua[391]: sl.cpp:348: SL[2]: duration 110 ms
61May 13 10:35:48 sip_ua[391]: comcerto.cpp:6311: channel 2: answer
62May 13 10:35:48 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP parameters, ts=2, flags=0000, data=10
63May 13 10:35:48 sip_ua[391]: comcerto.cpp:7147: --> ts 2: 192.168.20.69[10004] --> 192.168.20.69[10002]
64May 13 10:35:48 sip_ua[391]: comcerto.cpp:7148: --> ts 2: codec PCMA, VAD is on, red=0
65May 13 10:35:48 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
66May 13 10:35:48 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
67May 13 10:35:48 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
68May 13 10:35:48 sip_ua[391]: comcerto.cpp:7269: ts 2: RTP destination is 02:ad:c3:00:00:06
69May 13 10:35:48 sip_ua[449]: repro.cpp:832: doSessionAccounting(): Session Established 'branch=z9hG4bK402693463'
70May 13 10:35:48 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
71May 13 10:35:48 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
72May 13 10:35:48 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: RTP parameters, ts=1, flags=0000, data=9
73May 13 10:35:48 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
74May 13 10:35:48 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
75May 13 10:35:48 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
76May 13 10:35:48 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
77May 13 10:35:48 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
78May 13 10:35:48 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
79May 13 10:35:48 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Calling: Call answered, ts=1, flags=0000, data=9
80May 13 10:35:48 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP parameters, ts=2, flags=0000, data=10
81May 13 10:35:48 sip_ua[391]: comcerto.cpp:7147: --> ts 2: 192.168.20.69[10004] --> 192.168.20.69[10002]
82May 13 10:35:48 sip_ua[391]: comcerto.cpp:7148: --> ts 2: codec PCMA, VAD is on, red=0
83May 13 10:35:48 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
84May 13 10:35:48 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
85May 13 10:35:48 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
86May 13 10:35:48 sip_ua[391]: comcerto.cpp:7269: ts 2: RTP destination is 02:ad:c3:00:00:06
87May 13 10:35:48 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: ACK received, ts=2, flags=0000, data=10
88May 13 10:35:51 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
89May 13 10:35:51 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
90May 13 10:35:51 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
91May 13 10:35:51 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
92May 13 10:35:54 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: CAS event, ts=1, flags=0000, data=15
93May 13 10:35:54 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=0
94May 13 10:35:54 sip_ua[391]: comcerto.cpp:6993: ts 1: stopping RTP stream
95May 13 10:35:54 sip_ua[449]: repro.cpp:742: doSessionAccounting(): Session Ended 'branch=z9hG4bK1480847839'
96May 13 10:35:54 sip_ua[449]: repro.cpp:760: --> duration=6, [FXS]tel:11@192.168.20.69: ---> [SL]tel:22@192.168.20.69:
97May 13 10:35:54 sip_ua[449]: repro.cpp:1021: --> CDR: {"answer":1747132548,"duration":6,"hangup":1747132554,"org-host":"192.168.20.69","org-scheme":"tel","org-type":"FXS","org-user":"11","to-host":"192.168.20.69","to-scheme":"tel","to-type":"SL","to-u
98May 13 10:35:54 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=10, did=11, tid=18, rid=0, sid=0, nid=0
99May 13 10:35:54 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=10, did=11, tid=18, rid=0, sid=0, nid=0
100May 13 10:35:54 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Call disconnected, ts=1, flags=0000, data=9
101May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Call disconnected, ts=2, flags=0000, data=10
102May 13 10:35:54 sip_ua[391]: comcerto.cpp:6993: ts 2: stopping RTP stream
103May 13 10:35:54 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=9, did=-1, tid=17, rid=0, sid=0, nid=0
104May 13 10:35:54 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Message answered 2xx, ts=1, flags=0000, data=9
105May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: RTP monitor, ts=2, flags=0000, data=0
106May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=28
107May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=18
108May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: PassThru mode autoswitch, ts=2, flags=0000, data=1
109May 13 10:35:54 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 1
110May 13 10:35:54 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=255
111May 13 10:35:54 sip_ua[391]: sl.cpp:348: SL[2]: duration 150 ms
112May 13 10:35:57 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: TDM Call Progress detected, ts=2, flags=0000, data=81
113May 13 10:35:57 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: PassThru mode autoswitch, ts=2, flags=0000, data=0
114May 13 10:35:57 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 0
115May 13 10:35:58 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=28
116May 13 10:35:58 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=18
117May 13 10:35:58 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: PassThru mode autoswitch, ts=2, flags=0000, data=1
118May 13 10:35:58 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 1
119May 13 10:35:58 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: Tone detected, ts=2, flags=0000, data=255
120May 13 10:35:58 sip_ua[391]: sl.cpp:348: SL[2]: duration 170 ms
121May 13 10:35:59 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
122May 13 10:35:59 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: TDM Call Progress detected, ts=2, flags=0000, data=81
123May 13 10:35:59 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Blocked: PassThru mode autoswitch, ts=2, flags=0000, data=0
124May 13 10:35:59 sip_ua[391]: comcerto.cpp:5982: ts 2: PassThru mode autoswitch to 0