Ticket #413: log_debug.txt

File log_debug.txt, 46.8 KB (added by san, 8 months ago)
Line 
1
2root@comcerto:~# logread -f
3Sep 28 12:33:21 comcerto daemon.debug sip_ua[423]: poller.cpp:1749: duplicate re quest 183 (341 ms)
4Sep 28 12:33:22 comcerto daemon.debug sip_ua[423]: poller.cpp:1749: duplicate re quest 184 (347 ms)
5Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: Channel settings, ts=7, flags=0000, data=0
6Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: Channel settings, ts=8, flags=0000, data=0
7Sep 28 12:33:22 comcerto daemon.debug sip_ua[423]: poller.cpp:1749: duplicate re quest 186 (286 ms)
8Sep 28 12:33:22 comcerto daemon.debug sip_ua[423]: poller.cpp:1749: duplicate re quest 187 (303 ms)
9Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: DVO event, ts=-1, flags=0001, data=0
10Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: DVO event, ts=-1, flags=0001, data=0
11Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: DVO event, ts=-1, flags=0001, data=0
12Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: DVO event, ts=-1, flags=0001, data=0
13Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: DVO event, ts=-1, flags=0001, data=0
14Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: DVO event, ts=-1, flags=0001, data=0
15Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: DVO event, ts=-1, flags=0001, data=0
16Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: DVO event, ts=-1, flags=0001, data=0
17Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: DVO event, ts=-1, flags=0001, data=0
18Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: DVO event, ts=-1, flags=0001, data=0
19Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: DVO event, ts=-1, flags=0001, data=0
20Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=15, state =Idle: DVO event, ts=-1, flags=0001, data=0
21Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=17, state =Idle: DVO event, ts=-1, flags=0001, data=0
22Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: DVO event, ts=-1, flags=0001, data=0
23Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=19, state =Idle: DVO event, ts=-1, flags=0001, data=0
24Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: DVO event, ts=-1, flags=0001, data=0
25Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=34, state =Idle: DVO event, ts=-1, flags=0001, data=0
26Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=35, state =Idle: DVO event, ts=-1, flags=0001, data=0
27Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=37, state =Idle: DVO event, ts=-1, flags=0001, data=0
28Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=40, state =Idle: DVO event, ts=-1, flags=0001, data=0
29Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=41, state =Idle: DVO event, ts=-1, flags=0001, data=0
30Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=42, state =Idle: DVO event, ts=-1, flags=0001, data=0
31Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: DVO event, ts=-1, flags=0002, data=0
32Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: DVO event, ts=-1, flags=0002, data=0
33Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: DVO event, ts=-1, flags=0002, data=0
34Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: DVO event, ts=-1, flags=0002, data=0
35Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: DVO event, ts=-1, flags=0002, data=0
36Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=12, state =Idle: DVO event, ts=-1, flags=0002, data=0
37Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=13, state =Idle: DVO event, ts=-1, flags=0002, data=0
38Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=14, state =Idle: DVO event, ts=-1, flags=0002, data=0
39Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=15, state =Idle: DVO event, ts=-1, flags=0002, data=0
40Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=17, state =Idle: DVO event, ts=-1, flags=0002, data=0
41Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: DVO event, ts=-1, flags=0002, data=0
42Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: DVO event, ts=-1, flags=0001, data=0
43Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: DVO event, ts=-1, flags=0001, data=0
44Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: DVO event, ts=-1, flags=0001, data=0
45Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: DVO event, ts=-1, flags=0001, data=0
46Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: DVO event, ts=-1, flags=0001, data=0
47Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: DVO event, ts=-1, flags=0001, data=0
48Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: DVO event, ts=-1, flags=0001, data=0
49Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: DVO event, ts=-1, flags=0001, data=0
50Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: DVO event, ts=-1, flags=0001, data=0
51Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: DVO event, ts=-1, flags=0001, data=0
52Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: DVO event, ts=-1, flags=0001, data=0
53Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: DVO event, ts=-1, flags=0001, data=0
54Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: DVO event, ts=-1, flags=0002, data=0
55Sep 28 12:33:22 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: DVO event, ts=-1, flags=0002, data=0
56Sep 28 12:33:22 comcerto daemon.debug sip_ua[423]: poller.cpp:1749: duplicate re quest 190 (110 ms)
57Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command s slapply received
58Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command u serlist received
59Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: user_agent.cpp:284: User direc tory updated
60Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command c time received
61Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command c allgroups received
62Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command r outes received
63Sep 28 12:33:24 comcerto daemon.info sip_ua[423]: poller.cpp:835: ===> command c onferences received
64Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: Channel settings, ts=-1, flags=0001, data=0
65Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: Channel settings, ts=-1, flags=0001, data=0
66Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: Channel settings, ts=-1, flags=0001, data=0
67Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: Channel settings, ts=-1, flags=0001, data=0
68Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: Channel settings, ts=-1, flags=0001, data=0
69Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: Channel settings, ts=-1, flags=0001, data=0
70Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: Channel settings, ts=-1, flags=0001, data=0
71Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: Channel settings, ts=-1, flags=0001, data=0
72Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: Channel settings, ts=-1, flags=0001, data=0
73Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: Channel settings, ts=-1, flags=0001, data=0
74Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: Channel settings, ts=-1, flags=0001, data=0
75Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=19, state =Idle: Channel settings, ts=-1, flags=0001, data=0
76Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: Channel settings, ts=-1, flags=0001, data=0
77Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=34, state =Idle: Channel settings, ts=-1, flags=0001, data=0
78Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=35, state =Idle: Channel settings, ts=-1, flags=0001, data=0
79Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=37, state =Idle: Channel settings, ts=-1, flags=0001, data=0
80Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=40, state =Idle: Channel settings, ts=-1, flags=0001, data=0
81Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=41, state =Idle: Channel settings, ts=-1, flags=0001, data=0
82Sep 28 12:33:24 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=42, state =Idle: Channel settings, ts=-1, flags=0001, data=0
83Sep 28 12:33:36 comcerto user.info kernel: device eth0 entered promiscuous mode
84Sep 28 12:33:40 comcerto daemon.info sip_ua[595]: repro.cpp:608: doSessionAccoun ting(): Session Created 'branch=z9hG4bK2153457211'
85Sep 28 12:33:44 comcerto daemon.info sip_ua[595]: repro.cpp:608: doSessionAccoun ting(): Session Created 'branch=z9hG4bK3093223425'
86Sep 28 12:33:45 comcerto daemon.debug sip_ua[424]: user_agent.cpp:2187: ---> tra nsport 0: SIP event 2 (New call received!): cid=1810, did=1811, tid=2024, rid=0, sid=0, nid=0
87Sep 28 12:33:45 comcerto daemon.info sip_ua[424]: user_agent.cpp:2325: INVITE re ceived: sip:400@127.0.0.1:6060;transport=udp (Call-ID: 0_2715817587@192.168.0.10 5)
88Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: Incoming call, ts=-1, flags=0001, data=1810
89Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
90Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
91Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
92Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
93Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
94Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
95Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
96Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: Incoming call, ts=-1, flags=0001, data=1810
97Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
98Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
99Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
100Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=19, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
101Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
102Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=34, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
103Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=35, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
104Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=37, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
105Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=40, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
106Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=41, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
107Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=42, state =Idle: Incoming call, ts=-1, flags=0001, data=1810
108Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: virtualChannel.cpp:194: ---> V irtualChannelManager: Incoming call, ts=-1, flags=0001, data=1810
109Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: user_agent.cpp:4382: Call 1810 routed to TS 1005
110Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: DVO event, ts=-1, flags=0001, data=0
111Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: DVO event, ts=-1, flags=0001, data=0
112Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: DVO event, ts=-1, flags=0001, data=0
113Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: DVO event, ts=-1, flags=0001, data=0
114Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: DVO event, ts=-1, flags=0001, data=0
115Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: DVO event, ts=-1, flags=0001, data=0
116Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: DVO event, ts=-1, flags=0001, data=0
117Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: DVO event, ts=-1, flags=0001, data=0
118Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: DVO event, ts=-1, flags=0001, data=0
119Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: DVO event, ts=-1, flags=0001, data=0
120Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: DVO event, ts=-1, flags=0001, data=0
121Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=12, state =Idle: DVO event, ts=-1, flags=0001, data=0
122Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=13, state =Idle: DVO event, ts=-1, flags=0001, data=0
123Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=14, state =Idle: DVO event, ts=-1, flags=0001, data=0
124Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=15, state =Idle: DVO event, ts=-1, flags=0001, data=0
125Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=17, state =Idle: DVO event, ts=-1, flags=0001, data=0
126Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: DVO event, ts=-1, flags=0001, data=0
127Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=19, state =Idle: DVO event, ts=-1, flags=0001, data=0
128Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: DVO event, ts=-1, flags=0001, data=0
129Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=34, state =Idle: DVO event, ts=-1, flags=0001, data=0
130Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=35, state =Idle: DVO event, ts=-1, flags=0001, data=0
131Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=37, state =Idle: DVO event, ts=-1, flags=0001, data=0
132Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=40, state =Idle: DVO event, ts=-1, flags=0001, data=0
133Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=41, state =Idle: DVO event, ts=-1, flags=0001, data=0
134Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=42, state =Idle: DVO event, ts=-1, flags=0001, data=0
135Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=1, state= Ringing: DVO event, ts=-1, flags=0002, data=0
136Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=2, state= Idle: DVO event, ts=-1, flags=0002, data=0
137Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=3, state= Idle: DVO event, ts=-1, flags=0002, data=0
138Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=4, state= Idle: DVO event, ts=-1, flags=0002, data=0
139Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=5, state= Idle: DVO event, ts=-1, flags=0002, data=0
140Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=6, state= Idle: DVO event, ts=-1, flags=0002, data=0
141Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=7, state= Idle: DVO event, ts=-1, flags=0002, data=0
142Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=8, state= Idle: DVO event, ts=-1, flags=0002, data=0
143Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=9, state= Idle: DVO event, ts=-1, flags=0002, data=0
144Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=10, state =Idle: DVO event, ts=-1, flags=0002, data=0
145Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=11, state =Idle: DVO event, ts=-1, flags=0002, data=0
146Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=12, state =Idle: DVO event, ts=-1, flags=0002, data=0
147Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=13, state =Idle: DVO event, ts=-1, flags=0002, data=0
148Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=14, state =Idle: DVO event, ts=-1, flags=0002, data=0
149Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=15, state =Idle: DVO event, ts=-1, flags=0002, data=0
150Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=17, state =Idle: DVO event, ts=-1, flags=0002, data=0
151Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=18, state =Idle: DVO event, ts=-1, flags=0002, data=0
152Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=19, state =Idle: DVO event, ts=-1, flags=0002, data=0
153Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=33, state =Idle: DVO event, ts=-1, flags=0002, data=0
154Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=34, state =Idle: DVO event, ts=-1, flags=0002, data=0
155Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=35, state =Idle: DVO event, ts=-1, flags=0002, data=0
156Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxo.cpp:349: ---> ts=37, state =Idle: DVO event, ts=-1, flags=0002, data=0
157Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=40, state =Idle: DVO event, ts=-1, flags=0002, data=0
158Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=41, state =Idle: DVO event, ts=-1, flags=0002, data=0
159Sep 28 12:33:45 comcerto daemon.info sip_ua[399]: fxs.cpp:440: ---> ts=42, state =Idle: DVO event, ts=-1, flags=0002, data=0
160Sep 28 12:33:45 comcerto daemon.info sip_ua[595]: repro.cpp:832: doSessionAccoun ting(): Session Established 'branch=z9hG4bK3093223425'
161Sep 28 12:33:45 comcerto daemon.debug sip_ua[424]: user_agent.cpp:2187: ---> tra nsport 0: SIP event 12 (ACK received!): cid=1810, did=1811, tid=2024, rid=0, sid =0, nid=0
162Sep 28 12:33:50 comcerto daemon.info sip_ua[595]: repro.cpp:742: doSessionAccoun ting(): Session Ended 'branch=z9hG4bK455553037'
163Sep 28 12:33:50 comcerto daemon.info sip_ua[595]: repro.cpp:760: --> duration=5, []sip:527@192.168.0.4:5060 ---> []tel:400@192.168.0.4:5060
164Sep 28 12:33:50 comcerto daemon.info sip_ua[595]: repro.cpp:1021: --> CDR: {"ans wer":1695904425,"duration":5,"hangup":1695904430,"org-host":"192.168.0.4","org-n ame":"Александр Найданов","org-port":"5060","org-scheme":"sip","org-user":"527", "to-host":"192.
165Sep 28 12:33:50 comcerto daemon.debug sip_ua[424]: user_agent.cpp:2187: ---> tra nsport 0: SIP event 16 (2xx received for request!): cid=1810, did=-1, tid=2025, rid=0, sid=0, nid=0
166Sep 28 12:33:55 comcerto daemon.debug sip_ua[424]: user_agent.cpp:2187: ---> tra nsport 0: SIP event 22 (Call Context is released!): cid=1810, did=0, tid=0, rid= 0, sid=0, nid=0
167Sep 28 12:33:56 comcerto daemon.info sip_ua[595]: repro.cpp:783: doSessionAccoun ting(): Session Cancelled 'branch=z9hG4bK2153457211'
168Sep 28 12:34:02 comcerto user.info kernel: device eth0 left promiscuous mode