Ticket #455: messages_ve01_old

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