Ticket #456: messages_ve01_VBD_3

File messages_ve01_VBD_3, 8.3 KB (added by roman_zhur, 6 months ago)
Line 
1Apr 27 10:35:21 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=28
2Apr 27 10:35:21 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=255
3Apr 27 10:35:21 sip_ua[391]: sl.cpp:348: SL[2]: duration 90 ms
4Apr 27 10:35:22 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=28
5Apr 27 10:35:22 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=255
6Apr 27 10:35:22 sip_ua[391]: sl.cpp:348: SL[2]: duration 50 ms
7Apr 27 10:35:22 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone completed, ts=2, flags=0000, data=3
8Apr 27 10:35:23 sip_ua[391]: sl.cpp:187: ts 02: digit '1'
9Apr 27 10:35:23 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=28
10Apr 27 10:35:23 sip_ua[391]: sl.cpp:205: ---> ts=2, state=NumRecv: Tone detected, ts=2, flags=0000, data=255
11Apr 27 10:35:23 sip_ua[391]: sl.cpp:348: SL[2]: duration 50 ms
12Apr 27 10:35:24 sip_ua[391]: sl.cpp:187: ts 02: digit '1'
13Apr 27 10:35:24 sip_ua[391]: sl.cpp:81: ts 2: dialing 11
14Apr 27 10:35:24 sip_ua[391]: user_agent.cpp:3892: --> ua_dial_out() -> sip:11@192.168.20.69...
15Apr 27 10:35:24 sip_ua[448]: repro.cpp:608: doSessionAccounting(): Session Created 'branch=z9hG4bK1910988144'
16Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=54, did=55, tid=80, rid=0, sid=0, nid=0
17Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2272: INVITE received: sip:11@127.0.0.1:6060;transport=udp (Call-ID: 1134648153@(null))
18Apr 27 10:35:24 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=54
19Apr 27 10:35:24 sip_ua[391]: fxs.cpp:1020: channel 1: ringing
20Apr 27 10:35:24 sip_ua[391]: user_agent.cpp:4329: Call 54 routed to TS 1
21Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=53, did=56, tid=79, rid=0, sid=0, nid=0
22Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2499: ---> ringing with status code 180
23Apr 27 10:35:24 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Remote is ringing, ts=2, flags=0000, data=53
24Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=54, did=55, tid=82, rid=0, sid=0, nid=0
25Apr 27 10:35:24 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=53, did=56, tid=81, rid=0, sid=0, nid=0
26Apr 27 10:35:24 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Message answered 2xx, ts=2, flags=0000, data=53
27Apr 27 10:35:27 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Ringing: CAS event, ts=1, flags=0000, data=7
28Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:6311: channel 1: answer
29Apr 27 10:35:27 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP parameters, ts=1, flags=0000, data=54
30Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
31Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
32Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: -1/-1
33Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7207: ts 1: calling createConnection()
34Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
35Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
36Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
37Apr 27 10:35:27 sip_ua[391]: comcerto.cpp:6972: ts 1: starting RTP stream
38Apr 27 10:35:27 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: CAS event, ts=1, flags=0000, data=7
39Apr 27 10:35:28 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Calling: Call answered, ts=2, flags=0000, data=53
40Apr 27 10:35:28 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP parameters, ts=1, flags=0000, data=54
41Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7147: --> ts 1: 192.168.20.69[10002] --> 192.168.20.69[10004]
42Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7148: --> ts 1: codec PCMA, VAD is on, red=0
43Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7149: --> audio pt: 8/8, event pt: 101/101, VBD pt: -1/-1
44Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7248: routing 192.168.20.69 to 192.168.20.69 (lo)
45Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7259: getMac(192.168.20.69): 02:ad:c3:00:00:06
46Apr 27 10:35:28 sip_ua[391]: comcerto.cpp:7269: ts 1: RTP destination is 02:ad:c3:00:00:06
47Apr 27 10:35:28 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: ACK received, ts=1, flags=0000, data=54
48Apr 27 10:35:28 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
49Apr 27 10:35:28 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
50Apr 27 10:35:28 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
51Apr 27 10:35:28 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone completed, ts=2, flags=0000, data=0
52Apr 27 10:35:28 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=0
53Apr 27 10:35:29 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
54Apr 27 10:35:32 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=28
55Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
56Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
57Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=18
58Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
59Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Statistics received, ts=2, flags=0000, data=0
60Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=16
61Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: PassThru mode autoswitch, ts=1, flags=0000, data=2
62Apr 27 10:35:33 sip_ua[391]: comcerto.cpp:5982: ts 1: PassThru mode autoswitch to 2
63Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=255
64Apr 27 10:35:33 sip_ua[391]: sl.cpp:348: SL[2]: duration 510 ms
65Apr 27 10:35:33 sip_ua[391]: comcerto.cpp:6993: ts 2: stopping RTP stream
66Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Call disconnected, ts=2, flags=0000, data=53
67Apr 27 10:35:33 sip_ua[448]: repro.cpp:742: doSessionAccounting(): Session Ended 'branch=z9hG4bK1345696368'
68Apr 27 10:35:33 sip_ua[448]: repro.cpp:760: --> duration=6, [SL]tel:ve01@192.168.20.69: ---> [FXS]sip:11@192.168.20.69:
69Apr 27 10:35:33 sip_ua[448]: repro.cpp:1021: --> CDR: {"answer":1745750127,"duration":6,"hangup":1745750133,"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","to
70Apr 27 10:35:33 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=54, did=55, tid=84, rid=0, sid=0, nid=0
71Apr 27 10:35:33 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=54, did=55, tid=84, rid=0, sid=0, nid=0
72Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Connected: Call disconnected, ts=1, flags=0000, data=54
73Apr 27 10:35:33 sip_ua[391]: comcerto.cpp:6993: ts 1: stopping RTP stream
74Apr 27 10:35:33 sip_ua[391]: comcerto.cpp:6993: ts 1: stopping RTP stream
75Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Busy: Tone detected, ts=1, flags=0000, data=255
76Apr 27 10:35:33 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=53, did=-1, tid=83, rid=0, sid=0, nid=0
77Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: Message answered 2xx, ts=2, flags=0000, data=53
78Apr 27 10:35:33 sip_ua[391]: sl.cpp:205: ---> ts=2, state=Idle: RTP monitor, ts=2, flags=0000, data=0
79Apr 27 10:35:33 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Busy: RTP monitor, ts=1, flags=0000, data=0
80Apr 27 10:35:38 sip_ua[416]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=53, did=0, tid=0, rid=0, sid=0, nid=0
81Apr 27 10:35:39 sip_ua[391]: fxs.cpp:431: ---> ts=1, state=Busy: CAS event, ts=1, flags=0000, data=15