Ticket #373: messages

File messages, 140.0 KB (added by san, 4 years ago)
Line 
1Oct 8 09:52:55 syslogd started: BusyBox v1.18.5
2Oct 8 09:52:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
3Oct 8 09:53:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
4Oct 8 09:53:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
5Oct 8 09:53:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
6Oct 8 09:53:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
7Oct 8 09:53:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
8Oct 8 09:53:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
9Oct 8 09:53:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
10Oct 8 09:53:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
11Oct 8 09:53:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
12Oct 8 09:53:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
13Oct 8 09:53:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
14Oct 8 09:53:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
15Oct 8 09:53:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
16Oct 8 09:53:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
17Oct 8 09:53:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
18Oct 8 09:53:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
19Oct 8 09:53:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
20Oct 8 09:53:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
21Oct 8 09:53:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
22Oct 8 09:53:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
23Oct 8 09:53:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
24Oct 8 09:53:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
25Oct 8 09:53:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
26Oct 8 09:53:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
27Oct 8 09:54:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
28Oct 8 09:54:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
29Oct 8 09:54:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
30Oct 8 09:54:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
31Oct 8 09:54:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
32Oct 8 09:54:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
33Oct 8 09:54:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
34Oct 8 09:54:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
35Oct 8 09:54:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
36Oct 8 09:54:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
37Oct 8 09:54:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
38Oct 8 09:54:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
39Oct 8 09:54:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
40Oct 8 09:54:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
41Oct 8 09:54:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
42Oct 8 09:54:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
43Oct 8 09:54:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
44Oct 8 09:54:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
45Oct 8 09:54:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
46Oct 8 09:54:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
47Oct 8 09:54:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
48Oct 8 09:54:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
49Oct 8 09:54:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
50Oct 8 09:54:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
51Oct 8 09:55:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
52Oct 8 09:55:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
53Oct 8 09:55:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
54Oct 8 09:55:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
55Oct 8 09:55:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
56Oct 8 09:55:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
57Oct 8 09:55:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
58Oct 8 09:55:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
59Oct 8 09:55:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
60Oct 8 09:55:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
61Oct 8 09:55:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
62Oct 8 09:55:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
63Oct 8 09:55:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
64Oct 8 09:55:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
65Oct 8 09:55:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
66Oct 8 09:55:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
67Oct 8 09:55:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
68Oct 8 09:55:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
69Oct 8 09:55:47 syslogd exiting
70Oct 8 10:01:50 syslogd started: BusyBox v1.18.5
71Oct 8 10:01:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
72Oct 8 10:01:56 sip_ua[462]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
73Oct 8 10:01:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
74Oct 8 10:01:56 sip_ua[462]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
75Oct 8 10:01:56 sip_ua[487]: poller.cpp:871: ===> command userlist received
76Oct 8 10:01:56 sip_ua[487]: user_agent.cpp:201: User directory updated
77Oct 8 10:01:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
78Oct 8 10:01:58 sip_ua[462]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
79Oct 8 10:01:58 sip_ua[487]: poller.cpp:871: ===> command sslapply received
80Oct 8 10:01:58 sip_ua[487]: poller.cpp:871: ===> command userlist received
81Oct 8 10:01:58 sip_ua[487]: user_agent.cpp:201: User directory updated
82Oct 8 10:01:58 sip_ua[487]: poller.cpp:871: ===> command ctime received
83Oct 8 10:01:58 sip_ua[487]: poller.cpp:871: ===> command routes received
84Oct 8 10:02:01 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=9, did=0, tid=0, rid=0, sid=0, nid=0
85Oct 8 10:02:39 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---cf9d0e113122263f'
86Oct 8 10:02:39 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=25, did=26, tid=18, rid=0, sid=0, nid=0
87Oct 8 10:02:39 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1324684787@(null))
88Oct 8 10:02:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=25
89Oct 8 10:02:39 sip_ua[462]: user_agent.cpp:4048: Call 25 routed to TS 254
90Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
91Oct 8 10:02:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=25
92Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
93Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
94Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
95Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
96Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
97Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
98Oct 8 10:02:39 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
99Oct 8 10:02:39 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---cf9d0e113122263f'
100Oct 8 10:02:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
101Oct 8 10:02:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
102Oct 8 10:02:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
103Oct 8 10:02:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
104Oct 8 10:02:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
105Oct 8 10:02:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
106Oct 8 10:02:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
107Oct 8 10:02:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
108Oct 8 10:02:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
109Oct 8 10:02:56 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=25, did=26, tid=18, rid=0, sid=0, nid=0
110Oct 8 10:02:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=25
111Oct 8 10:02:57 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---a2014675840d8260'
112Oct 8 10:02:57 sip_ua[511]: repro.cpp:770: --> duration=18, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
113Oct 8 10:02:57 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687359,"duration":18,"hangup":1633687377,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
114Oct 8 10:02:57 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=25, did=26, tid=19, rid=0, sid=0, nid=0
115Oct 8 10:02:57 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=25, did=26, tid=19, rid=0, sid=0, nid=0
116Oct 8 10:02:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=25
117Oct 8 10:02:57 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
118Oct 8 10:02:57 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
119Oct 8 10:02:58 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---23073e6846eee03b'
120Oct 8 10:02:58 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=27, did=28, tid=20, rid=0, sid=0, nid=0
121Oct 8 10:02:58 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 2088521816@(null))
122Oct 8 10:02:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=27
123Oct 8 10:02:58 sip_ua[462]: user_agent.cpp:4048: Call 27 routed to TS 254
124Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
125Oct 8 10:02:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=27
126Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
127Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
128Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
129Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
130Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
131Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
132Oct 8 10:02:58 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---23073e6846eee03b'
133Oct 8 10:02:58 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
134Oct 8 10:02:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
135Oct 8 10:02:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
136Oct 8 10:02:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
137Oct 8 10:03:03 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
138Oct 8 10:03:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
139Oct 8 10:03:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
140Oct 8 10:03:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=27
141Oct 8 10:03:03 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
142Oct 8 10:03:03 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
143Oct 8 10:03:03 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK1165351334'
144Oct 8 10:03:03 sip_ua[511]: repro.cpp:770: --> duration=5, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
145Oct 8 10:03:03 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687378,"duration":5,"hangup":1633687383,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
146Oct 8 10:03:15 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=27, did=-1, tid=21, rid=0, sid=0, nid=0
147Oct 8 10:03:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Message failed, ts=254, flags=0000, data=27
148Oct 8 10:03:20 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=27, did=0, tid=0, rid=0, sid=0, nid=0
149Oct 8 10:03:29 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=25, did=0, tid=0, rid=0, sid=0, nid=0
150Oct 8 10:03:42 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---554fb9082ed67029'
151Oct 8 10:03:42 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=29, did=30, tid=22, rid=0, sid=0, nid=0
152Oct 8 10:03:42 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1945737895@(null))
153Oct 8 10:03:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=29
154Oct 8 10:03:42 sip_ua[462]: user_agent.cpp:4048: Call 29 routed to TS 254
155Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
156Oct 8 10:03:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=29
157Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
158Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
159Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
160Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
161Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
162Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
163Oct 8 10:03:42 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
164Oct 8 10:03:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
165Oct 8 10:03:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
166Oct 8 10:03:42 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---554fb9082ed67029'
167Oct 8 10:03:45 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=29, did=30, tid=22, rid=0, sid=0, nid=0
168Oct 8 10:03:45 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=29
169Oct 8 10:03:45 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
170Oct 8 10:03:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
171Oct 8 10:03:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
172Oct 8 10:03:47 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
173Oct 8 10:03:47 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
174Oct 8 10:03:50 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
175Oct 8 10:03:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
176Oct 8 10:03:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
177Oct 8 10:03:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
178Oct 8 10:03:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
179Oct 8 10:03:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2564
180Oct 8 10:03:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
181Oct 8 10:03:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
182Oct 8 10:03:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
183Oct 8 10:03:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
184Oct 8 10:04:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
185Oct 8 10:04:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
186Oct 8 10:04:02 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
187Oct 8 10:04:02 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
188Oct 8 10:04:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
189Oct 8 10:04:07 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
190Oct 8 10:04:07 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
191Oct 8 10:04:12 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
192Oct 8 10:04:12 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
193Oct 8 10:04:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
194Oct 8 10:04:17 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
195Oct 8 10:04:17 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
196Oct 8 10:04:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
197Oct 8 10:04:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
198Oct 8 10:04:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
199Oct 8 10:04:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
200Oct 8 10:04:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
201Oct 8 10:04:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
202Oct 8 10:04:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
203Oct 8 10:04:33 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---281e2d3b4db86671'
204Oct 8 10:04:33 sip_ua[511]: repro.cpp:770: --> duration=51, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
205Oct 8 10:04:33 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687422,"duration":51,"hangup":1633687473,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
206Oct 8 10:04:33 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=29, did=30, tid=23, rid=0, sid=0, nid=0
207Oct 8 10:04:33 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=29, did=30, tid=23, rid=0, sid=0, nid=0
208Oct 8 10:04:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=29
209Oct 8 10:04:33 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
210Oct 8 10:04:33 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
211Oct 8 10:05:05 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=29, did=0, tid=0, rid=0, sid=0, nid=0
212Oct 8 10:05:41 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---7a0b74042d15161a'
213Oct 8 10:05:41 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=31, did=32, tid=24, rid=0, sid=0, nid=0
214Oct 8 10:05:41 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1655292316@(null))
215Oct 8 10:05:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=31
216Oct 8 10:05:41 sip_ua[462]: user_agent.cpp:4048: Call 31 routed to TS 254
217Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
218Oct 8 10:05:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=31
219Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
220Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
221Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
222Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
223Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
224Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
225Oct 8 10:05:41 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---7a0b74042d15161a'
226Oct 8 10:05:41 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
227Oct 8 10:05:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
228Oct 8 10:05:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
229Oct 8 10:05:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
230Oct 8 10:05:46 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
231Oct 8 10:05:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=31
232Oct 8 10:05:46 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
233Oct 8 10:05:46 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK1480155058'
234Oct 8 10:05:46 sip_ua[511]: repro.cpp:770: --> duration=5, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
235Oct 8 10:05:46 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687541,"duration":5,"hangup":1633687546,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
236Oct 8 10:05:46 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
237Oct 8 10:05:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
238Oct 8 10:05:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
239Oct 8 10:05:59 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---ab6b0c033fb8e23c'
240Oct 8 10:06:00 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=33, did=34, tid=26, rid=0, sid=0, nid=0
241Oct 8 10:06:00 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1736783860@(null))
242Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=33
243Oct 8 10:06:00 sip_ua[462]: user_agent.cpp:4048: Call 33 routed to TS 254
244Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
245Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=33
246Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
247Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
248Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
249Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
250Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
251Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
252Oct 8 10:06:00 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---ab6b0c033fb8e23c'
253Oct 8 10:06:00 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
254Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
255Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
256Oct 8 10:06:00 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=33, did=34, tid=26, rid=0, sid=0, nid=0
257Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=33
258Oct 8 10:06:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
259Oct 8 10:06:02 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=31, did=-1, tid=25, rid=0, sid=0, nid=0
260Oct 8 10:06:02 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Message failed, ts=254, flags=0000, data=31
261Oct 8 10:06:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
262Oct 8 10:06:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
263Oct 8 10:06:08 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=31, did=0, tid=0, rid=0, sid=0, nid=0
264Oct 8 10:06:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
265Oct 8 10:06:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
266Oct 8 10:06:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
267Oct 8 10:06:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
268Oct 8 10:06:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
269Oct 8 10:06:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
270Oct 8 10:06:25 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
271Oct 8 10:06:25 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
272Oct 8 10:06:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
273Oct 8 10:06:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
274Oct 8 10:06:30 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---ade87f553486fc6c'
275Oct 8 10:06:30 sip_ua[511]: repro.cpp:770: --> duration=30, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
276Oct 8 10:06:30 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687560,"duration":30,"hangup":1633687590,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
277Oct 8 10:06:30 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=33, did=34, tid=27, rid=0, sid=0, nid=0
278Oct 8 10:06:30 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=33, did=34, tid=27, rid=0, sid=0, nid=0
279Oct 8 10:06:30 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=33
280Oct 8 10:06:30 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
281Oct 8 10:06:30 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
282Oct 8 10:06:30 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
283Oct 8 10:06:30 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
284Oct 8 10:06:31 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---2b5e57202a46f63f'
285Oct 8 10:06:31 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=35, did=36, tid=28, rid=0, sid=0, nid=0
286Oct 8 10:06:31 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1426391663@(null))
287Oct 8 10:06:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=35
288Oct 8 10:06:31 sip_ua[462]: user_agent.cpp:4048: Call 35 routed to TS 254
289Oct 8 10:06:31 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---2b5e57202a46f63f'
290Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
291Oct 8 10:06:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=35
292Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
293Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
294Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
295Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
296Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
297Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
298Oct 8 10:06:31 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
299Oct 8 10:06:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
300Oct 8 10:06:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
301Oct 8 10:06:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
302Oct 8 10:06:36 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
303Oct 8 10:06:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=35
304Oct 8 10:06:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
305Oct 8 10:06:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
306Oct 8 10:06:36 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK1707057446'
307Oct 8 10:06:36 sip_ua[511]: repro.cpp:770: --> duration=5, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
308Oct 8 10:06:36 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687591,"duration":5,"hangup":1633687596,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
309Oct 8 10:06:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
310Oct 8 10:06:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Statistics received, ts=254, flags=0000, data=0
311Oct 8 10:06:36 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=35, did=-1, tid=29, rid=0, sid=0, nid=0
312Oct 8 10:06:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Message failed, ts=254, flags=0000, data=35
313Oct 8 10:06:42 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=35, did=0, tid=0, rid=0, sid=0, nid=0
314Oct 8 10:06:48 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---1d76231e82c8434b'
315Oct 8 10:06:49 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=37, did=38, tid=30, rid=0, sid=0, nid=0
316Oct 8 10:06:49 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 942137453@(null))
317Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=37
318Oct 8 10:06:49 sip_ua[462]: user_agent.cpp:4048: Call 37 routed to TS 254
319Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
320Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=37
321Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
322Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
323Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
324Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
325Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
326Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
327Oct 8 10:06:49 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---1d76231e82c8434b'
328Oct 8 10:06:49 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
329Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
330Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
331Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
332Oct 8 10:06:49 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=37, did=38, tid=30, rid=0, sid=0, nid=0
333Oct 8 10:06:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=37
334Oct 8 10:06:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
335Oct 8 10:06:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
336Oct 8 10:06:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
337Oct 8 10:06:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2308
338Oct 8 10:06:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
339Oct 8 10:06:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
340Oct 8 10:06:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
341Oct 8 10:06:59 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---cb15f93a06d89364'
342Oct 8 10:06:59 sip_ua[511]: repro.cpp:770: --> duration=10, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
343Oct 8 10:06:59 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687609,"duration":10,"hangup":1633687619,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
344Oct 8 10:06:59 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=37, did=38, tid=31, rid=0, sid=0, nid=0
345Oct 8 10:06:59 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=37, did=38, tid=31, rid=0, sid=0, nid=0
346Oct 8 10:06:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=37
347Oct 8 10:06:59 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
348Oct 8 10:06:59 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
349Oct 8 10:07:00 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---3c067e7ec6fb1655'
350Oct 8 10:07:00 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=39, did=40, tid=32, rid=0, sid=0, nid=0
351Oct 8 10:07:00 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1272179746@(null))
352Oct 8 10:07:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=39
353Oct 8 10:07:00 sip_ua[462]: user_agent.cpp:4048: Call 39 routed to TS 254
354Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
355Oct 8 10:07:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=39
356Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
357Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
358Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
359Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
360Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
361Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
362Oct 8 10:07:00 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---3c067e7ec6fb1655'
363Oct 8 10:07:00 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
364Oct 8 10:07:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
365Oct 8 10:07:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
366Oct 8 10:07:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
367Oct 8 10:07:02 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=33, did=0, tid=0, rid=0, sid=0, nid=0
368Oct 8 10:07:05 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
369Oct 8 10:07:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
370Oct 8 10:07:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
371Oct 8 10:07:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=39
372Oct 8 10:07:05 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
373Oct 8 10:07:05 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK1099592293'
374Oct 8 10:07:05 sip_ua[511]: repro.cpp:770: --> duration=5, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
375Oct 8 10:07:05 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687620,"duration":5,"hangup":1633687625,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
376Oct 8 10:07:05 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
377Oct 8 10:07:06 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=39, did=-1, tid=33, rid=0, sid=0, nid=0
378Oct 8 10:07:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Message failed, ts=254, flags=0000, data=39
379Oct 8 10:07:11 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=39, did=0, tid=0, rid=0, sid=0, nid=0
380Oct 8 10:07:31 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=37, did=0, tid=0, rid=0, sid=0, nid=0
381Oct 8 10:09:04 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---1c2f5a2a03ac1071'
382Oct 8 10:09:04 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=41, did=42, tid=34, rid=0, sid=0, nid=0
383Oct 8 10:09:04 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 704230678@(null))
384Oct 8 10:09:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=41
385Oct 8 10:09:04 sip_ua[462]: user_agent.cpp:4048: Call 41 routed to TS 254
386Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
387Oct 8 10:09:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=41
388Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
389Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
390Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
391Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
392Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
393Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
394Oct 8 10:09:04 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
395Oct 8 10:09:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
396Oct 8 10:09:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
397Oct 8 10:09:04 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---1c2f5a2a03ac1071'
398Oct 8 10:09:08 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=41, did=42, tid=34, rid=0, sid=0, nid=0
399Oct 8 10:09:08 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=41
400Oct 8 10:09:08 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
401Oct 8 10:09:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
402Oct 8 10:09:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
403Oct 8 10:09:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
404Oct 8 10:09:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
405Oct 8 10:09:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
406Oct 8 10:09:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
407Oct 8 10:09:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
408Oct 8 10:09:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
409Oct 8 10:09:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
410Oct 8 10:09:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=260
411Oct 8 10:09:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
412Oct 8 10:09:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
413Oct 8 10:09:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
414Oct 8 10:09:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
415Oct 8 10:09:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
416Oct 8 10:09:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
417Oct 8 10:09:36 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---66462e25bab86011'
418Oct 8 10:09:36 sip_ua[511]: repro.cpp:770: --> duration=32, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
419Oct 8 10:09:36 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687744,"duration":32,"hangup":1633687776,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
420Oct 8 10:09:36 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=41, did=42, tid=35, rid=0, sid=0, nid=0
421Oct 8 10:09:36 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=41, did=42, tid=35, rid=0, sid=0, nid=0
422Oct 8 10:09:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=41
423Oct 8 10:09:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
424Oct 8 10:09:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
425Oct 8 10:09:37 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---0469dc26d6e3603c'
426Oct 8 10:09:37 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=43, did=44, tid=36, rid=0, sid=0, nid=0
427Oct 8 10:09:37 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1451644072@(null))
428Oct 8 10:09:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=43
429Oct 8 10:09:37 sip_ua[462]: user_agent.cpp:4048: Call 43 routed to TS 254
430Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
431Oct 8 10:09:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=43
432Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
433Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
434Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
435Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
436Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
437Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
438Oct 8 10:09:37 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---0469dc26d6e3603c'
439Oct 8 10:09:37 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
440Oct 8 10:09:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
441Oct 8 10:09:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
442Oct 8 10:09:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
443Oct 8 10:09:42 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=43, did=44, tid=36, rid=0, sid=0, nid=0
444Oct 8 10:09:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=43
445Oct 8 10:09:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
446Oct 8 10:09:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
447Oct 8 10:09:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
448Oct 8 10:09:45 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
449Oct 8 10:09:45 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
450Oct 8 10:09:47 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
451Oct 8 10:09:47 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
452Oct 8 10:09:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
453Oct 8 10:09:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
454Oct 8 10:09:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
455Oct 8 10:09:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
456Oct 8 10:09:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
457Oct 8 10:09:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
458Oct 8 10:10:02 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
459Oct 8 10:10:02 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
460Oct 8 10:10:07 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
461Oct 8 10:10:07 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
462Oct 8 10:10:07 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
463Oct 8 10:10:08 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=41, did=0, tid=0, rid=0, sid=0, nid=0
464Oct 8 10:10:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
465Oct 8 10:10:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
466Oct 8 10:10:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=260
467Oct 8 10:10:12 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
468Oct 8 10:10:12 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
469Oct 8 10:10:13 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---1486ea6cdc97523c'
470Oct 8 10:10:13 sip_ua[511]: repro.cpp:770: --> duration=36, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
471Oct 8 10:10:13 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687777,"duration":36,"hangup":1633687813,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
472Oct 8 10:10:13 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=43, did=44, tid=37, rid=0, sid=0, nid=0
473Oct 8 10:10:13 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=43, did=44, tid=37, rid=0, sid=0, nid=0
474Oct 8 10:10:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=43
475Oct 8 10:10:13 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
476Oct 8 10:10:13 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
477Oct 8 10:10:15 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---e1f19f0bc4f1c36d'
478Oct 8 10:10:15 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=45, did=46, tid=38, rid=0, sid=0, nid=0
479Oct 8 10:10:15 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 496878462@(null))
480Oct 8 10:10:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=45
481Oct 8 10:10:15 sip_ua[462]: user_agent.cpp:4048: Call 45 routed to TS 254
482Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
483Oct 8 10:10:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=45
484Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
485Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
486Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
487Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
488Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
489Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
490Oct 8 10:10:15 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---e1f19f0bc4f1c36d'
491Oct 8 10:10:15 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
492Oct 8 10:10:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
493Oct 8 10:10:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
494Oct 8 10:10:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
495Oct 8 10:10:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
496Oct 8 10:10:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
497Oct 8 10:10:20 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
498Oct 8 10:10:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=45
499Oct 8 10:10:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
500Oct 8 10:10:20 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK1225619423'
501Oct 8 10:10:20 sip_ua[511]: repro.cpp:770: --> duration=5, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
502Oct 8 10:10:20 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687815,"duration":5,"hangup":1633687820,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
503Oct 8 10:10:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
504Oct 8 10:10:45 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=43, did=0, tid=0, rid=0, sid=0, nid=0
505Oct 8 10:10:52 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=45, did=0, tid=0, rid=0, sid=0, nid=0
506Oct 8 10:11:05 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---47ba0739bc2c420e'
507Oct 8 10:11:05 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=47, did=48, tid=40, rid=0, sid=0, nid=0
508Oct 8 10:11:05 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1288109023@(null))
509Oct 8 10:11:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=47
510Oct 8 10:11:05 sip_ua[462]: user_agent.cpp:4048: Call 47 routed to TS 254
511Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
512Oct 8 10:11:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=47
513Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
514Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
515Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
516Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
517Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
518Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
519Oct 8 10:11:05 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---47ba0739bc2c420e'
520Oct 8 10:11:05 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
521Oct 8 10:11:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
522Oct 8 10:11:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
523Oct 8 10:11:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
524Oct 8 10:11:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
525Oct 8 10:11:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
526Oct 8 10:11:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
527Oct 8 10:11:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
528Oct 8 10:11:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
529Oct 8 10:11:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
530Oct 8 10:11:26 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
531Oct 8 10:11:26 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
532Oct 8 10:11:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
533Oct 8 10:11:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
534Oct 8 10:11:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
535Oct 8 10:11:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
536Oct 8 10:11:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
537Oct 8 10:11:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
538Oct 8 10:11:42 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=47, did=-1, tid=0, rid=0, sid=0, nid=0
539Oct 8 10:11:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=47
540Oct 8 10:11:42 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
541Oct 8 10:11:42 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK747075480'
542Oct 8 10:11:42 sip_ua[511]: repro.cpp:770: --> duration=37, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
543Oct 8 10:11:42 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687865,"duration":37,"hangup":1633687902,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
544Oct 8 10:11:42 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
545Oct 8 10:11:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
546Oct 8 10:11:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=15
547Oct 8 10:11:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
548Oct 8 10:11:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
549Oct 8 10:12:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
550Oct 8 10:12:03 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
551Oct 8 10:12:03 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
552Oct 8 10:12:03 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK1621881699'
553Oct 8 10:12:03 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=49, did=0, tid=42, rid=0, sid=0, nid=0
554Oct 8 10:12:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
555Oct 8 10:12:08 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=7
556Oct 8 10:12:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
557Oct 8 10:12:10 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=47, did=-1, tid=41, rid=0, sid=0, nid=0
558Oct 8 10:12:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Message failed, ts=254, flags=0000, data=47
559Oct 8 10:12:11 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=49, did=50, tid=42, rid=0, sid=0, nid=0
560Oct 8 10:12:11 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
561Oct 8 10:12:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=49
562Oct 8 10:12:12 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=49, did=50, tid=42, rid=0, sid=0, nid=0
563Oct 8 10:12:12 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
564Oct 8 10:12:12 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
565Oct 8 10:12:12 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=49
566Oct 8 10:12:13 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=49, did=50, tid=42, rid=0, sid=0, nid=0
567Oct 8 10:12:13 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
568Oct 8 10:12:13 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
569Oct 8 10:12:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=49
570Oct 8 10:12:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=7
571Oct 8 10:12:14 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK1621881699'
572Oct 8 10:12:14 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=49, did=50, tid=42, rid=0, sid=0, nid=0
573Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=49
574Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
575Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
576Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
577Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
578Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
579Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
580Oct 8 10:12:14 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
581Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=49
582Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
583Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
584Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
585Oct 8 10:12:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: CAS event, ts=254, flags=0000, data=15
586Oct 8 10:12:15 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=47, did=0, tid=0, rid=0, sid=0, nid=0
587Oct 8 10:12:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
588Oct 8 10:12:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
589Oct 8 10:12:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Hangup, ts=254, flags=0000, data=0
590Oct 8 10:12:21 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
591Oct 8 10:12:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Call disconnected, ts=254, flags=0000, data=49
592Oct 8 10:12:21 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK15060289'
593Oct 8 10:12:21 sip_ua[511]: repro.cpp:770: --> duration=7, [FO01]tel:100@10.200.255.4: ---> [FS01]tel:101@10.200.255.12:
594Oct 8 10:12:21 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687934,"duration":7,"hangup":1633687941,"org-host":"10.200.255.4","org-scheme":"tel","org-type":"FO01","org-user":"100","to-host":"10.200.255.12","to-scheme":"tel","to-type":"FS01","t
595Oct 8 10:12:25 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=49, did=-1, tid=44, rid=0, sid=0, nid=0
596Oct 8 10:12:25 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Message answered 2xx, ts=254, flags=0000, data=49
597Oct 8 10:12:26 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
598Oct 8 10:12:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
599Oct 8 10:12:30 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=49, did=0, tid=0, rid=0, sid=0, nid=0
600Oct 8 10:12:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
601Oct 8 10:12:31 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
602Oct 8 10:12:31 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
603Oct 8 10:12:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP monitor, ts=254, flags=0000, data=0
604Oct 8 10:12:31 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK1586845528'
605Oct 8 10:12:32 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=51, did=0, tid=45, rid=0, sid=0, nid=0
606Oct 8 10:12:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
607Oct 8 10:12:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=7
608Oct 8 10:12:36 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
609Oct 8 10:12:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call disconnected, ts=254, flags=0000, data=51
610Oct 8 10:12:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
611Oct 8 10:12:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
612Oct 8 10:12:36 sip_ua[511]: repro.cpp:793: doSessionAccounting(): Session Cancelled 'branch=z9hG4bK1586845528'
613Oct 8 10:12:37 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=51, did=0, tid=46, rid=0, sid=0, nid=0
614Oct 8 10:12:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Drop Line: Message answered 2xx, ts=254, flags=0000, data=51
615Oct 8 10:12:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Drop Line: CAS event, ts=254, flags=0000, data=15
616Oct 8 10:12:51 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---d130765eedf5c61e'
617Oct 8 10:12:51 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=52, did=53, tid=47, rid=0, sid=0, nid=0
618Oct 8 10:12:51 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1350482443@(null))
619Oct 8 10:12:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=52
620Oct 8 10:12:51 sip_ua[462]: user_agent.cpp:4048: Call 52 routed to TS 254
621Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
622Oct 8 10:12:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=52
623Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
624Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
625Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
626Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
627Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
628Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
629Oct 8 10:12:51 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
630Oct 8 10:12:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
631Oct 8 10:12:51 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---d130765eedf5c61e'
632Oct 8 10:12:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
633Oct 8 10:12:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
634Oct 8 10:12:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
635Oct 8 10:13:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
636Oct 8 10:13:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
637Oct 8 10:13:03 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 9 (4xx received for Call!): cid=51, did=0, tid=45, rid=0, sid=0, nid=0
638Oct 8 10:13:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=51
639Oct 8 10:13:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
640Oct 8 10:13:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
641Oct 8 10:13:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
642Oct 8 10:13:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
643Oct 8 10:13:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
644Oct 8 10:13:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
645Oct 8 10:13:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
646Oct 8 10:13:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
647Oct 8 10:13:26 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
648Oct 8 10:13:26 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
649Oct 8 10:13:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=52, did=-1, tid=0, rid=0, sid=0, nid=0
650Oct 8 10:13:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=52
651Oct 8 10:13:28 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
652Oct 8 10:13:28 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
653Oct 8 10:13:28 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK2087106170'
654Oct 8 10:13:28 sip_ua[511]: repro.cpp:770: --> duration=37, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
655Oct 8 10:13:28 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633687971,"duration":37,"hangup":1633688008,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
656Oct 8 10:13:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=51, did=0, tid=0, rid=0, sid=0, nid=0
657Oct 8 10:13:52 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 18 (4xx received for request!): cid=52, did=-1, tid=48, rid=0, sid=0, nid=0
658Oct 8 10:13:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Message failed, ts=254, flags=0000, data=52
659Oct 8 10:13:57 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=52, did=0, tid=0, rid=0, sid=0, nid=0
660Oct 8 10:16:53 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---2fc7cf7889ffab7b'
661Oct 8 10:16:53 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=54, did=55, tid=49, rid=0, sid=0, nid=0
662Oct 8 10:16:53 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 104239334@(null))
663Oct 8 10:16:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=54
664Oct 8 10:16:53 sip_ua[462]: user_agent.cpp:4048: Call 54 routed to TS 254
665Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
666Oct 8 10:16:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=54
667Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
668Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
669Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
670Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
671Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
672Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
673Oct 8 10:16:53 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---2fc7cf7889ffab7b'
674Oct 8 10:16:53 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
675Oct 8 10:16:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
676Oct 8 10:16:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
677Oct 8 10:16:54 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=54, did=55, tid=49, rid=0, sid=0, nid=0
678Oct 8 10:16:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=54
679Oct 8 10:16:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
680Oct 8 10:16:55 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
681Oct 8 10:16:55 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
682Oct 8 10:16:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
683Oct 8 10:16:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2308
684Oct 8 10:16:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=1284
685Oct 8 10:16:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
686Oct 8 10:16:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
687Oct 8 10:16:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
688Oct 8 10:17:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
689Oct 8 10:17:03 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
690Oct 8 10:17:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
691Oct 8 10:17:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
692Oct 8 10:17:08 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
693Oct 8 10:17:08 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
694Oct 8 10:17:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
695Oct 8 10:17:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
696Oct 8 10:17:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
697Oct 8 10:17:13 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
698Oct 8 10:17:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
699Oct 8 10:17:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
700Oct 8 10:17:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
701Oct 8 10:17:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
702Oct 8 10:17:25 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---88aa2503cb6bec3f'
703Oct 8 10:17:25 sip_ua[511]: repro.cpp:770: --> duration=32, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
704Oct 8 10:17:25 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688213,"duration":32,"hangup":1633688245,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
705Oct 8 10:17:25 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=54, did=55, tid=50, rid=0, sid=0, nid=0
706Oct 8 10:17:25 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=54, did=55, tid=50, rid=0, sid=0, nid=0
707Oct 8 10:17:25 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=54
708Oct 8 10:17:25 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
709Oct 8 10:17:25 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
710Oct 8 10:17:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
711Oct 8 10:17:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
712Oct 8 10:17:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
713Oct 8 10:17:37 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
714Oct 8 10:17:37 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
715Oct 8 10:17:37 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK1031975820'
716Oct 8 10:17:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP monitor, ts=254, flags=0000, data=0
717Oct 8 10:17:37 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=56, did=0, tid=51, rid=0, sid=0, nid=0
718Oct 8 10:17:38 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
719Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=56, did=57, tid=51, rid=0, sid=0, nid=0
720Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
721Oct 8 10:17:38 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=56
722Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=56, did=57, tid=51, rid=0, sid=0, nid=0
723Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
724Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
725Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=56, did=57, tid=51, rid=0, sid=0, nid=0
726Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
727Oct 8 10:17:38 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
728Oct 8 10:17:38 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=56
729Oct 8 10:17:38 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=56
730Oct 8 10:17:39 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=56, did=57, tid=52, rid=0, sid=0, nid=0
731Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Message answered 2xx, ts=254, flags=0000, data=56
732Oct 8 10:17:39 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK1031975820'
733Oct 8 10:17:39 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=56, did=57, tid=51, rid=0, sid=0, nid=0
734Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=56
735Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
736Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
737Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
738Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
739Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
740Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
741Oct 8 10:17:39 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
742Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=56
743Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
744Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
745Oct 8 10:17:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
746Oct 8 10:17:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
747Oct 8 10:17:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
748Oct 8 10:17:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
749Oct 8 10:17:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
750Oct 8 10:17:51 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---54b1c63a7ba1c90e'
751Oct 8 10:17:51 sip_ua[511]: repro.cpp:770: --> duration=12, [FO01]tel:100@10.200.255.4: ---> [FS01]tel:101@10.200.255.12:
752Oct 8 10:17:51 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688259,"duration":12,"hangup":1633688271,"org-host":"10.200.255.4","org-scheme":"tel","org-type":"FO01","org-user":"100","to-host":"10.200.255.12","to-scheme":"tel","to-type":"FS01","
753Oct 8 10:17:51 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=56, did=57, tid=53, rid=0, sid=0, nid=0
754Oct 8 10:17:51 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=56, did=57, tid=53, rid=0, sid=0, nid=0
755Oct 8 10:17:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=56
756Oct 8 10:17:51 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
757Oct 8 10:17:51 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
758Oct 8 10:17:52 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---ac8c027998d6dd61'
759Oct 8 10:17:52 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=58, did=59, tid=54, rid=0, sid=0, nid=0
760Oct 8 10:17:52 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1984463014@(null))
761Oct 8 10:17:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=58
762Oct 8 10:17:52 sip_ua[462]: user_agent.cpp:4048: Call 58 routed to TS 254
763Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
764Oct 8 10:17:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=58
765Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
766Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
767Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
768Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
769Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
770Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
771Oct 8 10:17:52 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
772Oct 8 10:17:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
773Oct 8 10:17:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
774Oct 8 10:17:52 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
775Oct 8 10:17:52 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---ac8c027998d6dd61'
776Oct 8 10:17:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
777Oct 8 10:17:53 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=58, did=59, tid=54, rid=0, sid=0, nid=0
778Oct 8 10:17:53 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=58
779Oct 8 10:17:55 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---447f672ecb22be0a'
780Oct 8 10:17:55 sip_ua[511]: repro.cpp:770: --> duration=3, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
781Oct 8 10:17:55 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688272,"duration":3,"hangup":1633688275,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
782Oct 8 10:17:55 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=58, did=59, tid=55, rid=0, sid=0, nid=0
783Oct 8 10:17:55 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=58, did=59, tid=55, rid=0, sid=0, nid=0
784Oct 8 10:17:55 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=58
785Oct 8 10:17:55 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
786Oct 8 10:17:55 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
787Oct 8 10:17:56 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---37cfed7732752e4d'
788Oct 8 10:17:56 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=60, did=61, tid=56, rid=0, sid=0, nid=0
789Oct 8 10:17:56 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1628878167@(null))
790Oct 8 10:17:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=60
791Oct 8 10:17:56 sip_ua[462]: user_agent.cpp:4048: Call 60 routed to TS 254
792Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
793Oct 8 10:17:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=60
794Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
795Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
796Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
797Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
798Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
799Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
800Oct 8 10:17:56 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---37cfed7732752e4d'
801Oct 8 10:17:56 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
802Oct 8 10:17:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
803Oct 8 10:17:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
804Oct 8 10:17:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
805Oct 8 10:17:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
806Oct 8 10:17:57 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=60, did=61, tid=56, rid=0, sid=0, nid=0
807Oct 8 10:17:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=60
808Oct 8 10:17:57 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=54, did=0, tid=0, rid=0, sid=0, nid=0
809Oct 8 10:17:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
810Oct 8 10:18:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2308
811Oct 8 10:18:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=1284
812Oct 8 10:18:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
813Oct 8 10:18:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
814Oct 8 10:18:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
815Oct 8 10:18:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
816Oct 8 10:18:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
817Oct 8 10:18:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
818Oct 8 10:18:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
819Oct 8 10:18:15 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
820Oct 8 10:18:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
821Oct 8 10:18:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
822Oct 8 10:18:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
823Oct 8 10:18:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
824Oct 8 10:18:20 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---8c47eb33196ce569'
825Oct 8 10:18:20 sip_ua[511]: repro.cpp:770: --> duration=24, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
826Oct 8 10:18:20 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688276,"duration":24,"hangup":1633688300,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
827Oct 8 10:18:20 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=60, did=61, tid=57, rid=0, sid=0, nid=0
828Oct 8 10:18:20 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=60, did=61, tid=57, rid=0, sid=0, nid=0
829Oct 8 10:18:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=60
830Oct 8 10:18:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
831Oct 8 10:18:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
832Oct 8 10:18:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
833Oct 8 10:18:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
834Oct 8 10:18:23 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=56, did=0, tid=0, rid=0, sid=0, nid=0
835Oct 8 10:18:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
836Oct 8 10:18:27 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
837Oct 8 10:18:27 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
838Oct 8 10:18:27 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK1011128601'
839Oct 8 10:18:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP monitor, ts=254, flags=0000, data=0
840Oct 8 10:18:27 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=62, did=0, tid=58, rid=0, sid=0, nid=0
841Oct 8 10:18:27 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=58, did=0, tid=0, rid=0, sid=0, nid=0
842Oct 8 10:18:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
843Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=62, did=63, tid=58, rid=0, sid=0, nid=0
844Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
845Oct 8 10:18:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=62
846Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=62, did=63, tid=58, rid=0, sid=0, nid=0
847Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
848Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
849Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=62, did=63, tid=58, rid=0, sid=0, nid=0
850Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
851Oct 8 10:18:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
852Oct 8 10:18:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=62
853Oct 8 10:18:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=62
854Oct 8 10:18:29 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=62, did=63, tid=59, rid=0, sid=0, nid=0
855Oct 8 10:18:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Message answered 2xx, ts=254, flags=0000, data=62
856Oct 8 10:18:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=7
857Oct 8 10:18:32 sip_ua[462]: comcerto.cpp:6365: ts 254: RTP timeout
858Oct 8 10:18:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call disconnected, ts=254, flags=0000, data=62
859Oct 8 10:18:32 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
860Oct 8 10:18:32 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
861Oct 8 10:18:32 sip_ua[511]: repro.cpp:793: doSessionAccounting(): Session Cancelled 'branch=z9hG4bK1011128601'
862Oct 8 10:18:32 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=62, did=63, tid=60, rid=0, sid=0, nid=0
863Oct 8 10:18:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Drop Line: Message answered 2xx, ts=254, flags=0000, data=62
864Oct 8 10:18:32 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Drop Line: CAS event, ts=254, flags=0000, data=15
865Oct 8 10:18:33 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 9 (4xx received for Call!): cid=62, did=63, tid=58, rid=0, sid=0, nid=0
866Oct 8 10:18:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Call disconnected, ts=254, flags=0000, data=62
867Oct 8 10:18:33 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---fcfb7e1eb306a942'
868Oct 8 10:18:33 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=64, did=65, tid=61, rid=0, sid=0, nid=0
869Oct 8 10:18:33 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 747808985@(null))
870Oct 8 10:18:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=64
871Oct 8 10:18:34 sip_ua[462]: user_agent.cpp:4048: Call 64 routed to TS 254
872Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
873Oct 8 10:18:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=64
874Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
875Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
876Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
877Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
878Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
879Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
880Oct 8 10:18:34 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---fcfb7e1eb306a942'
881Oct 8 10:18:34 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
882Oct 8 10:18:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
883Oct 8 10:18:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
884Oct 8 10:18:34 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=64, did=65, tid=61, rid=0, sid=0, nid=0
885Oct 8 10:18:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=64
886Oct 8 10:18:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
887Oct 8 10:18:36 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---b536dc5b746d4b50'
888Oct 8 10:18:36 sip_ua[511]: repro.cpp:770: --> duration=2, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
889Oct 8 10:18:36 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688314,"duration":2,"hangup":1633688316,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","t
890Oct 8 10:18:36 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=64, did=65, tid=62, rid=0, sid=0, nid=0
891Oct 8 10:18:36 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=64, did=65, tid=62, rid=0, sid=0, nid=0
892Oct 8 10:18:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=64
893Oct 8 10:18:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
894Oct 8 10:18:36 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
895Oct 8 10:18:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
896Oct 8 10:18:38 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
897Oct 8 10:18:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
898Oct 8 10:18:42 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
899Oct 8 10:18:42 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
900Oct 8 10:18:42 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK27004112'
901Oct 8 10:18:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP monitor, ts=254, flags=0000, data=0
902Oct 8 10:18:42 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=66, did=0, tid=63, rid=0, sid=0, nid=0
903Oct 8 10:18:43 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
904Oct 8 10:18:43 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=66, did=67, tid=63, rid=0, sid=0, nid=0
905Oct 8 10:18:43 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
906Oct 8 10:18:43 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=66
907Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=66, did=67, tid=63, rid=0, sid=0, nid=0
908Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
909Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
910Oct 8 10:18:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=66
911Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=66, did=67, tid=63, rid=0, sid=0, nid=0
912Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
913Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
914Oct 8 10:18:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=66
915Oct 8 10:18:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=66, did=67, tid=64, rid=0, sid=0, nid=0
916Oct 8 10:18:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Message answered 2xx, ts=254, flags=0000, data=66
917Oct 8 10:18:46 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK27004112'
918Oct 8 10:18:46 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=66, did=67, tid=63, rid=0, sid=0, nid=0
919Oct 8 10:18:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=66
920Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
921Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
922Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
923Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
924Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
925Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
926Oct 8 10:18:46 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
927Oct 8 10:18:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=66
928Oct 8 10:18:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
929Oct 8 10:18:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
930Oct 8 10:18:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
931Oct 8 10:18:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
932Oct 8 10:18:51 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
933Oct 8 10:18:52 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=60, did=0, tid=0, rid=0, sid=0, nid=0
934Oct 8 10:18:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
935Oct 8 10:18:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
936Oct 8 10:18:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
937Oct 8 10:18:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
938Oct 8 10:19:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
939Oct 8 10:19:01 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
940Oct 8 10:19:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
941Oct 8 10:19:05 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
942Oct 8 10:19:05 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=62, did=-1, tid=0, rid=0, sid=0, nid=0
943Oct 8 10:19:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
944Oct 8 10:19:06 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
945Oct 8 10:19:08 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=64, did=0, tid=0, rid=0, sid=0, nid=0
946Oct 8 10:19:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
947Oct 8 10:19:11 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
948Oct 8 10:19:16 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---085df81161fb7d59'
949Oct 8 10:19:16 sip_ua[511]: repro.cpp:770: --> duration=30, [FO01]tel:100@10.200.255.4: ---> [FS01]tel:101@10.200.255.12:
950Oct 8 10:19:16 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688326,"duration":30,"hangup":1633688356,"org-host":"10.200.255.4","org-scheme":"tel","org-type":"FO01","org-user":"100","to-host":"10.200.255.12","to-scheme":"tel","to-type":"FS01","
951Oct 8 10:19:16 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=66, did=67, tid=65, rid=0, sid=0, nid=0
952Oct 8 10:19:16 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=66, did=67, tid=65, rid=0, sid=0, nid=0
953Oct 8 10:19:16 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=66
954Oct 8 10:19:16 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
955Oct 8 10:19:16 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
956Oct 8 10:19:18 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---f66327113f2ce609'
957Oct 8 10:19:18 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=68, did=69, tid=66, rid=0, sid=0, nid=0
958Oct 8 10:19:18 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 480212593@(null))
959Oct 8 10:19:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=68
960Oct 8 10:19:18 sip_ua[462]: user_agent.cpp:4048: Call 68 routed to TS 254
961Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
962Oct 8 10:19:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=68
963Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
964Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
965Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
966Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
967Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
968Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
969Oct 8 10:19:18 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
970Oct 8 10:19:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
971Oct 8 10:19:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
972Oct 8 10:19:18 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
973Oct 8 10:19:18 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---f66327113f2ce609'
974Oct 8 10:19:19 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=68, did=69, tid=66, rid=0, sid=0, nid=0
975Oct 8 10:19:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=68
976Oct 8 10:19:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
977Oct 8 10:19:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
978Oct 8 10:19:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
979Oct 8 10:19:21 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=1028
980Oct 8 10:19:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
981Oct 8 10:19:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
982Oct 8 10:19:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
983Oct 8 10:19:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
984Oct 8 10:19:24 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
985Oct 8 10:19:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
986Oct 8 10:19:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
987Oct 8 10:19:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
988Oct 8 10:19:31 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
989Oct 8 10:19:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
990Oct 8 10:19:33 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
991Oct 8 10:19:37 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---bd45ff3ba41df26d'
992Oct 8 10:19:37 sip_ua[511]: repro.cpp:770: --> duration=19, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
993Oct 8 10:19:37 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688358,"duration":19,"hangup":1633688377,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
994Oct 8 10:19:37 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=68, did=69, tid=67, rid=0, sid=0, nid=0
995Oct 8 10:19:37 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=68, did=69, tid=67, rid=0, sid=0, nid=0
996Oct 8 10:19:37 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=68
997Oct 8 10:19:37 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
998Oct 8 10:19:37 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
999Oct 8 10:19:39 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK-524287-1---12ea8b46c06c1625'
1000Oct 8 10:19:39 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 2 (New call received!): cid=70, did=71, tid=68, rid=0, sid=0, nid=0
1001Oct 8 10:19:39 sip_ua[488]: user_agent.cpp:2222: INVITE received: sip:100@127.0.0.1:6060;transport=udp (Call-ID: 1611397548@(null))
1002Oct 8 10:19:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: Incoming call, ts=-1, flags=0001, data=70
1003Oct 8 10:19:39 sip_ua[462]: user_agent.cpp:4048: Call 70 routed to TS 254
1004Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:6330: channel 254: answer
1005Oct 8 10:19:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP parameters, ts=254, flags=0000, data=70
1006Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
1007Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
1008Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
1009Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
1010Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
1011Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
1012Oct 8 10:19:39 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
1013Oct 8 10:19:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1014Oct 8 10:19:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1015Oct 8 10:19:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1016Oct 8 10:19:39 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK-524287-1---12ea8b46c06c1625'
1017Oct 8 10:19:40 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 12 (ACK received!): cid=70, did=71, tid=68, rid=0, sid=0, nid=0
1018Oct 8 10:19:40 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: ACK received, ts=254, flags=0000, data=70
1019Oct 8 10:19:40 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1020Oct 8 10:19:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1021Oct 8 10:19:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1022Oct 8 10:19:41 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=772
1023Oct 8 10:19:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=2052
1024Oct 8 10:19:42 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=516
1025Oct 8 10:19:43 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Remote tone detected, ts=254, flags=0000, data=260
1026Oct 8 10:19:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1027Oct 8 10:19:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1028Oct 8 10:19:45 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1029Oct 8 10:19:46 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1030Oct 8 10:19:48 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=66, did=0, tid=0, rid=0, sid=0, nid=0
1031Oct 8 10:19:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1032Oct 8 10:19:49 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1033Oct 8 10:19:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1034Oct 8 10:19:54 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1035Oct 8 10:19:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1036Oct 8 10:19:56 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1037Oct 8 10:19:57 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=51
1038Oct 8 10:19:58 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
1039Oct 8 10:19:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1040Oct 8 10:19:59 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1041Oct 8 10:20:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1042Oct 8 10:20:00 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1043Oct 8 10:20:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1044Oct 8 10:20:04 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1045Oct 8 10:20:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1046Oct 8 10:20:09 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1047Oct 8 10:20:10 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=68, did=0, tid=0, rid=0, sid=0, nid=0
1048Oct 8 10:20:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=0
1049Oct 8 10:20:10 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1050Oct 8 10:20:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1051Oct 8 10:20:14 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1052Oct 8 10:20:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1053Oct 8 10:20:19 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1054Oct 8 10:20:20 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---90bc3f799bed9c1b'
1055Oct 8 10:20:20 sip_ua[511]: repro.cpp:770: --> duration=41, [FS01]tel:101@10.200.255.12: ---> [FO01]tel:100@10.200.255.4:
1056Oct 8 10:20:20 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688379,"duration":41,"hangup":1633688420,"org-host":"10.200.255.12","org-scheme":"tel","org-type":"FS01","org-user":"101","to-host":"10.200.255.4","to-scheme":"tel","to-type":"FO01","
1057Oct 8 10:20:20 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=70, did=71, tid=69, rid=0, sid=0, nid=0
1058Oct 8 10:20:20 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=70, did=71, tid=69, rid=0, sid=0, nid=0
1059Oct 8 10:20:20 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=70
1060Oct 8 10:20:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
1061Oct 8 10:20:20 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
1062Oct 8 10:20:22 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=7
1063Oct 8 10:20:23 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=15
1064Oct 8 10:20:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Ringing: CAS event, ts=254, flags=0000, data=7
1065Oct 8 10:20:27 sip_ua[462]: fxo.cpp:266: ts 254: dialing 101@10.200.255.12
1066Oct 8 10:20:27 sip_ua[462]: user_agent.cpp:3612: --> ua_dial_out() <sip:100@10.200.255.4> -> sip:101@10.200.255.12 ()...
1067Oct 8 10:20:27 sip_ua[511]: repro.cpp:618: doSessionAccounting(): Session Created 'branch=z9hG4bK50465012'
1068Oct 8 10:20:27 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP monitor, ts=254, flags=0000, data=0
1069Oct 8 10:20:27 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 5 (Call is being processed!): cid=72, did=0, tid=70, rid=0, sid=0, nid=0
1070Oct 8 10:20:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: CAS event, ts=254, flags=0000, data=15
1071Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=72, did=73, tid=70, rid=0, sid=0, nid=0
1072Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
1073Oct 8 10:20:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=72
1074Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=72, did=73, tid=70, rid=0, sid=0, nid=0
1075Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
1076Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
1077Oct 8 10:20:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=72
1078Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=72, did=73, tid=70, rid=0, sid=0, nid=0
1079Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2464: eXosip_call_build_prack(): error -3
1080Oct 8 10:20:28 sip_ua[488]: user_agent.cpp:2472: ---> ringing with status code 180
1081Oct 8 10:20:28 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=72
1082Oct 8 10:20:29 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 16 (2xx received for request!): cid=72, did=73, tid=71, rid=0, sid=0, nid=0
1083Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Message answered 2xx, ts=254, flags=0000, data=72
1084Oct 8 10:20:29 sip_ua[511]: repro.cpp:842: doSessionAccounting(): Session Established 'branch=z9hG4bK50465012'
1085Oct 8 10:20:29 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=72, did=73, tid=70, rid=0, sid=0, nid=0
1086Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=72
1087Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7101: --> ts 254: 10.200.255.4[10508] --> 10.200.255.12[10508]
1088Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7102: --> ts 254: codec G729, VAD is on, red=0
1089Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7103: --> audio pt: 18/18, event pt: 101/101, VBD pt: 98/98
1090Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7205: routing 10.200.255.12 to 10.200.255.1 (eth0)
1091Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7221: getMac(10.200.255.1): 46:13:41:33:68:56
1092Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:7231: ts 254: RTP destination is 46:13:41:33:68:56
1093Oct 8 10:20:29 sip_ua[462]: comcerto.cpp:6926: ts 254: starting RTP stream
1094Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=72
1095Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1096Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1097Oct 8 10:20:29 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
1098Oct 8 10:20:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1099Oct 8 10:20:34 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1100Oct 8 10:20:35 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
1101Oct 8 10:20:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
1102Oct 8 10:20:36 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: TDM Call Progress detected, ts=254, flags=0000, data=53
1103Oct 8 10:20:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1104Oct 8 10:20:39 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1105Oct 8 10:20:43 sip_ua[511]: repro.cpp:752: doSessionAccounting(): Session Ended 'branch=z9hG4bK-524287-1---5a7e08533a61a166'
1106Oct 8 10:20:43 sip_ua[511]: repro.cpp:770: --> duration=14, [FO01]tel:100@10.200.255.4: ---> [FS01]tel:101@10.200.255.12:
1107Oct 8 10:20:43 sip_ua[511]: repro.cpp:1031: --> CDR: {"answer":1633688429,"duration":14,"hangup":1633688443,"org-host":"10.200.255.4","org-scheme":"tel","org-type":"FO01","org-user":"100","to-host":"10.200.255.12","to-scheme":"tel","to-type":"FS01","
1108Oct 8 10:20:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 14 (New request received!): cid=72, did=73, tid=72, rid=0, sid=0, nid=0
1109Oct 8 10:20:44 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 21 (Bye Received!): cid=72, did=73, tid=72, rid=0, sid=0, nid=0
1110Oct 8 10:20:44 sip_ua[462]: fxo.cpp:345: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=72
1111Oct 8 10:20:44 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
1112Oct 8 10:20:44 sip_ua[462]: comcerto.cpp:6947: ts 254: stopping RTP stream
1113Oct 8 10:20:52 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=70, did=0, tid=0, rid=0, sid=0, nid=0
1114Oct 8 10:21:15 sip_ua[488]: user_agent.cpp:2095: ---> transport 0: SIP event 22 (Call Context is released!): cid=72, did=0, tid=0, rid=0, sid=0, nid=0
1115Oct 8 10:21:23 syslogd exiting
1116Oct 21 07:17:32 syslogd started: BusyBox v1.18.5
1117Oct 21 07:17:33 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1118Oct 21 07:17:36 sip_ua[479]: poller.cpp:871: ===> command globalconf received
1119Oct 21 07:17:36 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0001, data=0
1120Oct 21 07:17:36 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
1121Oct 21 07:17:36 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0002, data=0
1122Oct 21 07:17:36 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
1123Oct 21 07:17:36 sip_ua[479]: poller.cpp:871: ===> command userlist received
1124Oct 21 07:17:36 sip_ua[479]: user_agent.cpp:201: User directory updated
1125Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1126Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1127Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
1128Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command sslapply received
1129Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command userlist received
1130Oct 21 07:17:37 sip_ua[479]: user_agent.cpp:201: User directory updated
1131Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command ctime received
1132Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command routes received
1133Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1134Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1135Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command globalconf received
1136Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0001, data=0
1137Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
1138Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0002, data=0
1139Oct 21 07:17:37 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
1140Oct 21 07:17:37 sip_ua[479]: poller.cpp:871: ===> command userlist received
1141Oct 21 07:17:37 sip_ua[479]: user_agent.cpp:201: User directory updated
1142Oct 21 07:17:38 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1143Oct 21 07:17:39 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1144Oct 21 07:17:39 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
1145Oct 21 07:17:39 sip_ua[479]: poller.cpp:871: ===> command sslapply received
1146Oct 21 07:17:39 sip_ua[479]: poller.cpp:871: ===> command userlist received
1147Oct 21 07:17:39 sip_ua[479]: user_agent.cpp:201: User directory updated
1148Oct 21 07:17:39 sip_ua[479]: poller.cpp:871: ===> command ctime received
1149Oct 21 07:17:40 sip_ua[479]: poller.cpp:871: ===> command routes received
1150Oct 21 07:17:42 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1151Oct 21 07:17:42 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1152Oct 21 07:17:47 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1153Oct 21 07:17:47 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1154Oct 21 07:17:52 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1155Oct 21 07:17:52 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1156Oct 21 07:17:56 syslogd exiting
1157Oct 21 07:29:45 syslogd started: BusyBox v1.18.5
1158Oct 21 07:29:48 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1159Oct 21 07:29:49 sip_ua[479]: poller.cpp:871: ===> command globalconf received
1160Oct 21 07:29:49 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0001, data=0
1161Oct 21 07:29:49 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
1162Oct 21 07:29:49 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0002, data=0
1163Oct 21 07:29:49 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
1164Oct 21 07:29:49 sip_ua[479]: poller.cpp:871: ===> command userlist received
1165Oct 21 07:29:49 sip_ua[479]: user_agent.cpp:201: User directory updated
1166Oct 21 07:29:50 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1167Oct 21 07:29:50 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1168Oct 21 07:29:51 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
1169Oct 21 07:29:51 sip_ua[479]: poller.cpp:871: ===> command sslapply received
1170Oct 21 07:29:51 sip_ua[479]: poller.cpp:871: ===> command userlist received
1171Oct 21 07:29:51 sip_ua[479]: user_agent.cpp:201: User directory updated
1172Oct 21 07:29:51 sip_ua[479]: poller.cpp:871: ===> command ctime received
1173Oct 21 07:29:51 sip_ua[479]: poller.cpp:871: ===> command routes received
1174Oct 21 07:29:52 sip_ua[479]: poller.cpp:871: ===> command globalconf received
1175Oct 21 07:29:52 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0001, data=0
1176Oct 21 07:29:52 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
1177Oct 21 07:29:52 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=-1, flags=0002, data=0
1178Oct 21 07:29:52 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
1179Oct 21 07:29:52 sip_ua[479]: poller.cpp:871: ===> command userlist received
1180Oct 21 07:29:52 sip_ua[479]: user_agent.cpp:201: User directory updated
1181Oct 21 07:29:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1182Oct 21 07:29:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Channel settings, ts=254, flags=0000, data=0
1183Oct 21 07:29:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1184Oct 21 07:29:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1185Oct 21 07:29:54 sip_ua[454]: fxo.cpp:345: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
1186Oct 21 07:29:54 sip_ua[479]: poller.cpp:871: ===> command sslapply received
1187Oct 21 07:29:54 sip_ua[479]: poller.cpp:871: ===> command userlist received
1188Oct 21 07:29:54 sip_ua[479]: user_agent.cpp:201: User directory updated
1189Oct 21 07:29:54 sip_ua[479]: poller.cpp:871: ===> command ctime received
1190Oct 21 07:29:54 sip_ua[479]: poller.cpp:871: ===> command routes received
1191Oct 21 07:29:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1192Oct 21 07:29:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1193Oct 21 07:30:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1194Oct 21 07:30:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1195Oct 21 07:30:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1196Oct 21 07:30:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1197Oct 21 07:30:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1198Oct 21 07:30:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1199Oct 21 07:30:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1200Oct 21 07:30:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1201Oct 21 07:30:23 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1202Oct 21 07:30:23 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1203Oct 21 07:30:28 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1204Oct 21 07:30:28 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1205Oct 21 07:30:33 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1206Oct 21 07:30:33 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1207Oct 21 07:30:38 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1208Oct 21 07:30:38 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1209Oct 21 07:30:43 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1210Oct 21 07:30:43 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1211Oct 21 07:30:48 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1212Oct 21 07:30:48 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1213Oct 21 07:30:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1214Oct 21 07:30:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1215Oct 21 07:30:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1216Oct 21 07:30:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1217Oct 21 07:31:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1218Oct 21 07:31:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1219Oct 21 07:31:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1220Oct 21 07:31:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1221Oct 21 07:31:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1222Oct 21 07:31:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1223Oct 21 07:31:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1224Oct 21 07:31:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1225Oct 21 07:31:23 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1226Oct 21 07:31:23 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1227Oct 21 07:31:28 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1228Oct 21 07:31:28 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1229Oct 21 07:31:33 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1230Oct 21 07:31:33 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1231Oct 21 07:31:38 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1232Oct 21 07:31:38 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1233Oct 21 07:31:43 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1234Oct 21 07:31:43 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1235Oct 21 07:31:48 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1236Oct 21 07:31:48 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1237Oct 21 07:31:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1238Oct 21 07:31:53 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1239Oct 21 07:31:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1240Oct 21 07:31:58 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1241Oct 21 07:32:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1242Oct 21 07:32:03 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1243Oct 21 07:32:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1244Oct 21 07:32:08 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1245Oct 21 07:32:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1246Oct 21 07:32:13 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1247Oct 21 07:32:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1248Oct 21 07:32:18 sip_ua[454]: fxo.cpp:345: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
1249Oct 21 07:32:18 syslogd exiting