Ticket #455: messages_ve02

File messages_ve02, 14.4 KB (added by roman_zhur, 22 hours ago)
Line 
1May 14 08:05:28 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Idle: CAS event, ts=1, flags=0000, data=5
2May 14 08:05:28 sip_ua[454]: fxs.cpp:275: ts 1: dialing 11
3May 14 08:05:28 sip_ua[454]: user_agent.cpp:3892: --> ua_dial_out() <sip:22@192.168.20.70> -> sip:11@192.168.20.70...
4May 14 08:05:28 sip_ua[518]: repro.cpp:644: doSessionAccounting(): Session Created 'branch=z9hG4bK1417911548'
5May 14 08:05:28 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 2 (New call received!): cid=46, did=47, tid=66, rid=0, sid=0, nid=0
6May 14 08:05:28 sip_ua[478]: user_agent.cpp:2272: INVITE received: sip:11@127.0.0.1:6060;transport=udp (Call-ID: 1721993766@(null))
7May 14 08:05:28 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Incoming call, ts=-1, flags=0001, data=46
8May 14 08:05:28 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=46
9May 14 08:05:28 sip_ua[454]: virtualChannel.cpp:217: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=46
10May 14 08:05:28 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Incoming call, ts=-1, flags=0002, data=46
11May 14 08:05:28 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=46
12May 14 08:05:28 sip_ua[454]: user_agent.cpp:4329: Call 46 routed to TS 2
13May 14 08:05:28 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: CAS event, ts=1, flags=0000, data=5
14May 14 08:05:29 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 5 (Call is being processed!): cid=45, did=0, tid=65, rid=0, sid=0, nid=0
15May 14 08:05:29 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
16May 14 08:05:30 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
17May 14 08:05:30 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialing: Tone completed, ts=2, flags=0000, data=0
18May 14 08:05:31 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: RTP parameters, ts=2, flags=0000, data=46
19May 14 08:05:31 sip_ua[454]: comcerto.cpp:7297: --> ts 2: 192.168.20.70[10004] --> 192.168.20.70[10002]
20May 14 08:05:31 sip_ua[454]: comcerto.cpp:7298: --> ts 2: codec PCMA, VAD is on, red=0
21May 14 08:05:31 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
22May 14 08:05:31 sip_ua[454]: comcerto.cpp:7357: ts 2: calling createConnection()
23May 14 08:05:31 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=45, did=48, tid=65, rid=0, sid=0, nid=0
24May 14 08:05:31 sip_ua[478]: user_agent.cpp:2499: ---> ringing with status code 183
25May 14 08:05:31 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=46, did=47, tid=68, rid=0, sid=0, nid=0
26May 14 08:05:31 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.70 to 192.168.20.70 (lo)
27May 14 08:05:31 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.70): 02:ad:c5:00:00:69
28May 14 08:05:31 sip_ua[454]: comcerto.cpp:7423: ts 2: RTP destination is 02:ad:c5:00:00:69
29May 14 08:05:31 sip_ua[454]: comcerto.cpp:7122: ts 2: starting RTP stream
30May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: RTP parameters, ts=1, flags=0000, data=45
31May 14 08:05:31 sip_ua[454]: comcerto.cpp:7297: --> ts 1: 192.168.20.70[10002] --> 192.168.20.70[10004]
32May 14 08:05:31 sip_ua[454]: comcerto.cpp:7298: --> ts 1: codec PCMA, VAD is on, red=0
33May 14 08:05:31 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
34May 14 08:05:31 sip_ua[454]: comcerto.cpp:7357: ts 1: calling createConnection()
35May 14 08:05:31 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=45, did=48, tid=67, rid=0, sid=0, nid=0
36May 14 08:05:31 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.70 to 192.168.20.70 (lo)
37May 14 08:05:31 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.70): 02:ad:c5:00:00:69
38May 14 08:05:31 sip_ua[454]: comcerto.cpp:7423: ts 1: RTP destination is 02:ad:c5:00:00:69
39May 14 08:05:31 sip_ua[454]: comcerto.cpp:7122: ts 1: starting RTP stream
40May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Remote is ringing, ts=1, flags=0000, data=45
41May 14 08:05:31 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
42May 14 08:05:31 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
43May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Message answered 2xx, ts=1, flags=0000, data=45
44May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: CAS event, ts=1, flags=0000, data=5
45May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=1
46May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Statistics received, ts=1, flags=0000, data=0
47May 14 08:05:31 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Statistics received, ts=1, flags=0000, data=0
48May 14 08:05:31 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=1
49May 14 08:05:33 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=0
50May 14 08:05:33 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=0
51May 14 08:05:35 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: RTP monitor, ts=1, flags=0000, data=1
52May 14 08:05:35 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=1
53May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: RTP monitor, ts=2, flags=0000, data=0
54May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Tone detected, ts=2, flags=0000, data=28
55May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
56May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Statistics received, ts=2, flags=0000, data=0
57May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Dialed: Tone detected, ts=2, flags=0000, data=255
58May 14 08:05:36 sip_ua[454]: sl.cpp:348: SL[2]: duration 90 ms
59May 14 08:05:36 sip_ua[454]: comcerto.cpp:6461: channel 2: answer
60May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP parameters, ts=2, flags=0000, data=46
61May 14 08:05:36 sip_ua[454]: comcerto.cpp:7297: --> ts 2: 192.168.20.70[10004] --> 192.168.20.70[10002]
62May 14 08:05:36 sip_ua[454]: comcerto.cpp:7298: --> ts 2: codec PCMA, VAD is on, red=0
63May 14 08:05:36 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
64May 14 08:05:36 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.70 to 192.168.20.70 (lo)
65May 14 08:05:36 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.70): 02:ad:c5:00:00:69
66May 14 08:05:36 sip_ua[454]: comcerto.cpp:7423: ts 2: RTP destination is 02:ad:c5:00:00:69
67May 14 08:05:36 sip_ua[518]: repro.cpp:868: doSessionAccounting(): Session Established 'branch=z9hG4bK1417911548'
68May 14 08:05:36 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=45, did=48, tid=65, rid=0, sid=0, nid=0
69May 14 08:05:36 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 12 (ACK received!): cid=46, did=47, tid=66, rid=0, sid=0, nid=0
70May 14 08:05:36 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
71May 14 08:05:36 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: RTP parameters, ts=1, flags=0000, data=45
72May 14 08:05:36 sip_ua[454]: comcerto.cpp:7297: --> ts 1: 192.168.20.70[10002] --> 192.168.20.70[10004]
73May 14 08:05:37 sip_ua[454]: comcerto.cpp:7298: --> ts 1: codec PCMA, VAD is on, red=0
74May 14 08:05:37 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
75May 14 08:05:37 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.70 to 192.168.20.70 (lo)
76May 14 08:05:37 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.70): 02:ad:c5:00:00:69
77May 14 08:05:37 sip_ua[454]: comcerto.cpp:7423: ts 1: RTP destination is 02:ad:c5:00:00:69
78May 14 08:05:37 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Calling: Call answered, ts=1, flags=0000, data=45
79May 14 08:05:37 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP parameters, ts=2, flags=0000, data=46
80May 14 08:05:37 sip_ua[454]: comcerto.cpp:7297: --> ts 2: 192.168.20.70[10004] --> 192.168.20.70[10002]
81May 14 08:05:37 sip_ua[454]: comcerto.cpp:7298: --> ts 2: codec PCMA, VAD is on, red=0
82May 14 08:05:37 sip_ua[454]: comcerto.cpp:7299: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
83May 14 08:05:37 sip_ua[454]: comcerto.cpp:7401: routing 192.168.20.70 to 192.168.20.70 (lo)
84May 14 08:05:37 sip_ua[454]: comcerto.cpp:7413: getMac(192.168.20.70): 02:ad:c5:00:00:69
85May 14 08:05:37 sip_ua[454]: comcerto.cpp:7423: ts 2: RTP destination is 02:ad:c5:00:00:69
86May 14 08:05:37 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: ACK received, ts=2, flags=0000, data=46
87May 14 08:05:37 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
88May 14 08:05:37 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: Statistics received, ts=1, flags=0000, data=0
89May 14 08:05:37 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=0
90May 14 08:05:37 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=0
91May 14 08:05:39 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
92May 14 08:05:39 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=28
93May 14 08:05:40 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=18
94May 14 08:05:40 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: PassThru mode autoswitch, ts=2, flags=0000, data=1
95May 14 08:05:40 sip_ua[454]: comcerto.cpp:6132: ts 2: PassThru mode autoswitch to 1
96May 14 08:05:40 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: Tone detected, ts=2, flags=0000, data=255
97May 14 08:05:40 sip_ua[454]: sl.cpp:348: SL[2]: duration 150 ms
98May 14 08:05:40 sip_ua[454]: sl.cpp:391: SL[2]: unexpected short pulse received in state Connected
99May 14 08:05:40 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: PassThru mode autoswitch, ts=1, flags=0000, data=2
100May 14 08:05:40 sip_ua[454]: comcerto.cpp:6132: ts 1: PassThru mode autoswitch to 2
101May 14 08:05:40 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
102May 14 08:05:40 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
103May 14 08:05:40 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=16
104May 14 08:05:40 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: Tone detected, ts=1, flags=0000, data=255
105May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: TDM Call Progress detected, ts=1, flags=0000, data=81
106May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: PassThru mode autoswitch, ts=1, flags=0000, data=0
107May 14 08:05:41 sip_ua[454]: comcerto.cpp:6132: ts 1: PassThru mode autoswitch to 0
108May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: CAS event, ts=1, flags=0000, data=15
109May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Connected: RTP monitor, ts=1, flags=0000, data=1
110May 14 08:05:41 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: PassThru mode autoswitch, ts=2, flags=0000, data=0
111May 14 08:05:41 sip_ua[454]: comcerto.cpp:6132: ts 2: PassThru mode autoswitch to 0
112May 14 08:05:41 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: RTP monitor, ts=2, flags=0000, data=1
113May 14 08:05:41 sip_ua[454]: comcerto.cpp:7143: ts 1: stopping RTP stream
114May 14 08:05:41 sip_ua[518]: repro.cpp:778: doSessionAccounting(): Session Ended 'branch=z9hG4bK1663930430'
115May 14 08:05:41 sip_ua[518]: repro.cpp:796: --> duration=5, [FXS]tel:22@192.168.20.70: ---> [SL]tel:11@192.168.20.70:
116May 14 08:05:41 sip_ua[518]: repro.cpp:1057: --> CDR: {"answer":1747209936,"duration":5,"hangup":1747209941,"org-host":"192.168.20.70","org-scheme":"tel","org-type":"FXS","org-user":"22","to-host":"192.168.20.70","to-scheme":"tel","to-type":"SL","to-u
117May 14 08:05:41 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 14 (New request received!): cid=46, did=47, tid=70, rid=0, sid=0, nid=0
118May 14 08:05:41 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 21 (Bye Received!): cid=46, did=47, tid=70, rid=0, sid=0, nid=0
119May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Idle: Call disconnected, ts=1, flags=0000, data=45
120May 14 08:05:41 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Connected: Call disconnected, ts=2, flags=0000, data=46
121May 14 08:05:41 sip_ua[454]: comcerto.cpp:7143: ts 2: stopping RTP stream
122May 14 08:05:41 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 16 (2xx received for request!): cid=45, did=-1, tid=69, rid=0, sid=0, nid=0
123May 14 08:05:41 sip_ua[454]: fxs.cpp:434: ---> ts=1, state=Idle: Message answered 2xx, ts=1, flags=0000, data=45
124May 14 08:05:41 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Blocked: RTP monitor, ts=2, flags=0000, data=0
125May 14 08:05:42 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Blocked: Tone completed, ts=2, flags=0000, data=0
126May 14 08:05:44 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=28
127May 14 08:05:45 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=18
128May 14 08:05:45 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: PassThru mode autoswitch, ts=2, flags=0000, data=1
129May 14 08:05:45 sip_ua[454]: comcerto.cpp:6132: ts 2: PassThru mode autoswitch to 1
130May 14 08:05:45 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Tone detected, ts=2, flags=0000, data=255
131May 14 08:05:45 sip_ua[454]: sl.cpp:348: SL[2]: duration 150 ms
132May 14 08:05:46 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=45, did=0, tid=0, rid=0, sid=0, nid=0
133May 14 08:06:00 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Blocked: Tone completed, ts=2, flags=0000, data=5
134May 14 08:06:01 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: Tone completed, ts=2, flags=0000, data=0
135May 14 08:06:16 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: TDM Call Progress detected, ts=2, flags=0000, data=81
136May 14 08:06:16 sip_ua[454]: sl.cpp:205: ---> ts=2, state=Idle: PassThru mode autoswitch, ts=2, flags=0000, data=0
137May 14 08:06:16 sip_ua[454]: comcerto.cpp:6132: ts 2: PassThru mode autoswitch to 0
138May 14 08:06:23 sip_ua[478]: user_agent.cpp:2134: ---> transport 0: SIP event 22 (Call Context is released!): cid=46, did=0, tid=0, rid=0, sid=0, nid=0