Ticket #473: messages3

File messages3, 21.2 KB (added by roman_zhur, 6 hours ago)
Line 
1Feb 11 05:44:54 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
2Feb 11 05:45:15 sip_ua[477]: poller.cpp:2613: switching to CRC32 mode
3Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
4Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
5Feb 11 05:45:15 sip_ua[477]: poller.cpp:923: ===> command globalconf received
6Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
7Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
8Feb 11 05:45:15 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
9Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
10Feb 11 05:45:15 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
11Feb 11 05:45:15 sip_ua[477]: poller.cpp:923: ===> command userlist received
12Feb 11 05:45:15 sip_ua[477]: repro.cpp:1954: User directory updated
13Feb 11 05:45:16 sip_ua[477]: poller.cpp:2529: duplicate request 159 (123 ms)
14Feb 11 05:45:16 sip_ua[477]: poller.cpp:2529: duplicate request 158 (392 ms)
15Feb 11 05:45:17 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
16Feb 11 05:45:17 sip_ua[477]: poller.cpp:2529: duplicate request 161 (240 ms)
17Feb 11 05:45:17 sip_ua[477]: poller.cpp:2529: duplicate request 162 (248 ms)
18Feb 11 05:45:17 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
19Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command sslapply received
20Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command userlist received
21Feb 11 05:45:17 sip_ua[477]: repro.cpp:1954: User directory updated
22Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command ctime received
23Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command callgroups received
24Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command routes received
25Feb 11 05:45:17 sip_ua[477]: poller.cpp:923: ===> command conferences received
26Feb 11 05:45:17 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
27Feb 11 05:45:17 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
28Feb 11 05:45:18 init: starting pid 259, tty '/dev/ttyS0': '/bin/ash --login'
29Feb 11 05:45:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=5
30Feb 11 05:45:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=5
31Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=2
32Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
33Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
34Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
35Feb 11 05:45:36 sip_ua[454]: fxs.cpp:275: ts 254: dialing 22
36Feb 11 05:45:36 sip_ua[454]: user_agent.cpp:3909: --> ua_dial_out() <sip:11@192.168.20.120> -> sip:22@192.168.20.120...
37Feb 11 05:45:36 sip_ua[556]: repro.cpp:658: doSessionAccounting(): Session Created 'branch=z9hG4bK1921569820'
38Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 2 (New call received!): cid=2, did=3, tid=2, rid=0, sid=0, nid=0
39Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2289: INVITE received: sip:22@127.0.0.1:6060;transport=udp (Call-ID: 522098068@(null))
40Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Incoming call, ts=-1, flags=0001, data=2
41Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Incoming call, ts=-1, flags=0001, data=2
42Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 5 (Call is being processed!): cid=1, did=0, tid=1, rid=0, sid=0, nid=0
43Feb 11 05:45:36 sip_ua[454]: fxs.cpp:1023: channel 255: ringing
44Feb 11 05:45:36 sip_ua[454]: user_agent.cpp:4353: Call 2 routed to TS 255
45Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 6 (Remote phone is ringing!): cid=1, did=4, tid=1, rid=0, sid=0, nid=0
46Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2516: ---> ringing with status code 180
47Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 14 (New request received!): cid=2, did=3, tid=4, rid=0, sid=0, nid=0
48Feb 11 05:45:36 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 16 (2xx received for request!): cid=1, did=4, tid=3, rid=0, sid=0, nid=0
49Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Tone detected, ts=254, flags=0000, data=255
50Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Remote is ringing, ts=254, flags=0000, data=1
51Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Message answered 2xx, ts=254, flags=0000, data=1
52Feb 11 05:45:36 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Ringing: CAS event, ts=255, flags=0000, data=13
53Feb 11 05:45:38 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Ringing: CAS event, ts=255, flags=0000, data=5
54Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:6464: channel 255: answer
55Feb 11 05:45:38 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: RTP parameters, ts=255, flags=0000, data=2
56Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7300: --> ts 255: 192.168.20.120[10510] --> 192.168.20.120[10508]
57Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7301: --> ts 255: codec PCMA, VAD is on, red=0
58Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7302: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
59Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7406: routing 192.168.20.120 to 192.168.20.120 (lo)
60Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7418: getMac(192.168.20.120): 02:ad:c5:00:03:8f
61Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7428: ts 255: RTP destination is 02:ad:c5:00:03:8f
62Feb 11 05:45:38 sip_ua[556]: repro.cpp:882: doSessionAccounting(): Session Established 'branch=z9hG4bK1921569820'
63Feb 11 05:45:38 sip_ua[454]: comcerto.cpp:7146: ts 255: stopping RTP stream
64Feb 11 05:45:38 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 7 (Remote phone has answered!): cid=1, did=4, tid=1, rid=0, sid=0, nid=0
65Feb 11 05:45:38 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 12 (ACK received!): cid=2, did=3, tid=2, rid=0, sid=0, nid=0
66Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7146: ts 255: stopping RTP stream
67Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7125: ts 255: starting RTP stream
68Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: Caller ID sending complete, ts=255, flags=0000, data=1
69Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: RTP parameters, ts=254, flags=0000, data=1
70Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7300: --> ts 254: 192.168.20.120[10508] --> 192.168.20.120[10510]
71Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7301: --> ts 254: codec PCMA, VAD is on, red=0
72Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7302: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
73Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7406: routing 192.168.20.120 to 192.168.20.120 (lo)
74Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7418: getMac(192.168.20.120): 02:ad:c5:00:03:8f
75Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7428: ts 254: RTP destination is 02:ad:c5:00:03:8f
76Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
77Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
78Feb 11 05:45:39 sip_ua[454]: comcerto.cpp:7125: ts 254: starting RTP stream
79Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Call answered, ts=254, flags=0000, data=1
80Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: ACK received, ts=255, flags=0000, data=2
81Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: Statistics received, ts=255, flags=0000, data=0
82Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: Statistics received, ts=255, flags=0000, data=0
83Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: RTP monitor, ts=254, flags=0000, data=1
84Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Tone completed, ts=254, flags=0000, data=3
85Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
86Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
87Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: RTP monitor, ts=255, flags=0000, data=1
88Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: RTP monitor, ts=255, flags=0000, data=0
89Feb 11 05:45:39 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: RTP monitor, ts=255, flags=0000, data=1
90Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: Statistics received, ts=255, flags=0000, data=0
91Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: Statistics received, ts=255, flags=0000, data=0
92Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
93Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Statistics received, ts=254, flags=0000, data=0
94Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Connected: CAS event, ts=255, flags=0000, data=13
95Feb 11 05:45:44 sip_ua[454]: comcerto.cpp:7146: ts 255: stopping RTP stream
96Feb 11 05:45:44 sip_ua[556]: repro.cpp:792: doSessionAccounting(): Session Ended 'branch=z9hG4bK1340716402'
97Feb 11 05:45:44 sip_ua[556]: repro.cpp:810: --> duration=6, [FS01]tel:11@192.168.20.120: ---> [FS01]sip:22@192.168.20.120:
98Feb 11 05:45:44 sip_ua[556]: repro.cpp:1071: --> CDR: {"answer":1770788738,"duration":6,"hangup":1770788744,"org-host":"192.168.20.120","org-scheme":"tel","org-type":"FS01","org-user":"11","to-host":"192.168.20.120","to-scheme":"sip","to-type":"FS01",
99Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Call disconnected, ts=255, flags=0000, data=2
100Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: CAS event, ts=255, flags=0000, data=13
101Feb 11 05:45:44 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 14 (New request received!): cid=1, did=4, tid=6, rid=0, sid=0, nid=0
102Feb 11 05:45:44 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 21 (Bye Received!): cid=1, did=4, tid=6, rid=0, sid=0, nid=0
103Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Connected: Call disconnected, ts=254, flags=0000, data=1
104Feb 11 05:45:44 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
105Feb 11 05:45:44 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
106Feb 11 05:45:44 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 16 (2xx received for request!): cid=2, did=-1, tid=5, rid=0, sid=0, nid=0
107Feb 11 05:45:44 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Message answered 2xx, ts=255, flags=0000, data=2
108Feb 11 05:45:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: CAS event, ts=254, flags=0000, data=13
109Feb 11 05:45:45 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=13
110Feb 11 05:45:49 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 22 (Call Context is released!): cid=2, did=0, tid=0, rid=0, sid=0, nid=0
111Feb 11 05:46:07 sip_ua[477]: poller.cpp:923: ===> command globalconf received
112Feb 11 05:46:07 sip_ua[454]: poller.cpp:1279: DHCP client started (pid=593)
113Feb 11 05:46:07 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
114Feb 11 05:46:07 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
115Feb 11 05:46:07 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
116Feb 11 05:46:07 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
117Feb 11 05:46:07 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
118Feb 11 05:46:07 udhcpc[593]: udhcpc (v1.18.5) started
119Feb 11 05:46:07 udhcpc[593]: Sending discover...
120Feb 11 05:46:08 udhcpc[593]: Sending select for 192.168.0.124...
121Feb 11 05:46:09 udhcpc[593]: Lease of 192.168.0.124 obtained, lease time 14400
122Feb 11 05:46:09 sip_ua[477]: poller.cpp:2529: duplicate request 3 (2071 ms)
123Feb 11 05:46:09 sip_ua[454]: sip_ua.cpp:1128: IP change 192.168.20.120 --> 192.168.0.124 detected
124Feb 11 05:46:11 dnsmasq[376]: reading /tmp/resolv.conf.auto
125Feb 11 05:46:11 dnsmasq[376]: using nameserver 8.8.8.8#53
126Feb 11 05:46:11 dnsmasq[376]: using nameserver 192.168.0.13#53
127Feb 11 05:46:11 dnsmasq[376]: using nameserver 212.33.224.133#53
128Feb 11 05:46:11 dnsmasq[376]: using only locally-known addresses for lan
129Feb 11 05:46:11 sip_ua[454]: repro.cpp:1570: --> skipping secure transport: no cert file
130Feb 11 05:46:11 sip_ua[454]: repro.cpp:1570: --> skipping secure transport: no cert file
131Feb 11 05:46:11 sip_ua[477]: poller.cpp:923: ===> command globalconf received
132Feb 11 05:46:11 sip_ua[454]: comcerto.cpp:2639: comcerto_process_socket(): channel 0xffff: transaction 0x043f02 not found (020a 0602 043f 0000 0000 0000)
133Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0001, data=1
134Feb 11 05:46:11 sip_ua[454]: user_agent.cpp:3821: ---> registering <sip:11@192.168.20.120> on sip:192.168.20.120 as <sip:11@192.168.0.124:5060;line=254>
135Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0001, data=1
136Feb 11 05:46:11 sip_ua[454]: user_agent.cpp:3821: ---> registering <sip:22@192.168.20.120> on sip:192.168.20.120 as <sip:22@192.168.0.124:5060;line=255>
137Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0002, data=1
138Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0002, data=1
139Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
140Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
141Feb 11 05:46:11 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
142Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
143Feb 11 05:46:11 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
144Feb 11 05:46:12 sip_ua[477]: poller.cpp:2529: duplicate request 11 (342 ms)
145Feb 11 05:46:13 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
146Feb 11 05:46:13 sip_ua[477]: poller.cpp:2529: duplicate request 15 (177 ms)
147Feb 11 05:46:13 sip_ua[477]: poller.cpp:2529: duplicate request 14 (448 ms)
148Feb 11 05:46:13 sip_ua[477]: poller.cpp:923: ===> command sslapply received
149Feb 11 05:46:13 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
150Feb 11 05:46:13 sip_ua[477]: poller.cpp:2529: duplicate request 18 (128 ms)
151Feb 11 05:46:13 sip_ua[477]: poller.cpp:2529: duplicate request 17 (301 ms)
152Feb 11 05:46:13 sip_ua[477]: poller.cpp:923: ===> command userlist received
153Feb 11 05:46:13 sip_ua[477]: repro.cpp:1954: User directory updated
154Feb 11 05:46:14 sip_ua[477]: poller.cpp:923: ===> command ctime received
155Feb 11 05:46:14 sip_ua[477]: poller.cpp:923: ===> command callgroups received
156Feb 11 05:46:14 sip_ua[477]: poller.cpp:923: ===> command routes received
157Feb 11 05:46:14 sip_ua[477]: poller.cpp:923: ===> command conferences received
158Feb 11 05:46:14 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
159Feb 11 05:46:14 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
160Feb 11 05:46:16 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 22 (Call Context is released!): cid=1, did=0, tid=0, rid=0, sid=0, nid=0
161Feb 11 05:46:18 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
162Feb 11 05:46:31 sip_ua[477]: poller.cpp:923: ===> command globalconf received
163Feb 11 05:46:31 udhcpc[593]: Received SIGTERM
164Feb 11 05:46:31 udhcpc[593]: Unicasting a release of 192.168.0.124 to 192.168.0.13
165Feb 11 05:46:31 udhcpc[593]: Sending release...
166Feb 11 05:46:31 udhcpc[593]: Entering released state
167Feb 11 05:46:31 sip_ua[454]: poller.cpp:3345: DHCP client (pid 593) exited with return code 0
168Feb 11 05:46:31 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
169Feb 11 05:46:31 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
170Feb 11 05:46:31 dnsmasq[376]: reading /tmp/resolv.conf.auto
171Feb 11 05:46:31 dnsmasq[376]: using nameserver 8.8.8.8#53
172Feb 11 05:46:31 dnsmasq[376]: using only locally-known addresses for lan
173Feb 11 05:46:31 sip_ua[454]: virtualChannel.cpp:195: cannot open /proc/sys/net/ipv6/conf/eth0/proxy_ndp: No such file or directory
174Feb 11 05:46:31 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0002, data=0
175Feb 11 05:46:31 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0002, data=0
176Feb 11 05:46:32 sip_ua[454]: sip_ua.cpp:1128: IP change 192.168.0.124 --> 192.168.20.120 detected
177Feb 11 05:46:32 sip_ua[477]: poller.cpp:2529: duplicate request 3 (1601 ms)
178Feb 11 05:46:33 sip_ua[477]: poller.cpp:2529: duplicate request 3 (2452 ms)
179Feb 11 05:46:34 sip_ua[454]: repro.cpp:1570: --> skipping secure transport: no cert file
180Feb 11 05:46:34 sip_ua[454]: repro.cpp:1570: --> skipping secure transport: no cert file
181Feb 11 05:46:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0001, data=1
182Feb 11 05:46:34 sip_ua[454]: user_agent.cpp:3865: cannot build REGISTER message (rc=-3)
183Feb 11 05:46:34 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0001, data=1
184Feb 11 05:46:34 sip_ua[454]: user_agent.cpp:3865: cannot build REGISTER message (rc=-3)
185Feb 11 05:46:34 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: IP address changed, ts=-1, flags=0002, data=1
186Feb 11 05:46:34 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: IP address changed, ts=-1, flags=0002, data=1
187Feb 11 05:46:35 sip_ua[477]: poller.cpp:2529: duplicate request 8 (433 ms)
188Feb 11 05:46:35 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=254, flags=0000, data=0
189Feb 11 05:46:36 sip_ua[477]: poller.cpp:2529: duplicate request 10 (227 ms)
190Feb 11 05:46:36 sip_ua[477]: poller.cpp:2529: duplicate request 11 (236 ms)
191Feb 11 05:46:36 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=255, flags=0000, data=0
192Feb 11 05:46:36 sip_ua[477]: poller.cpp:923: ===> command sslapply received
193Feb 11 05:46:36 sip_ua[477]: poller.cpp:923: ===> command userlist received
194Feb 11 05:46:36 sip_ua[477]: repro.cpp:1954: User directory updated
195Feb 11 05:46:36 sip_ua[477]: poller.cpp:923: ===> command ctime received
196Feb 11 05:46:37 sip_ua[477]: poller.cpp:923: ===> command callgroups received
197Feb 11 05:46:37 sip_ua[477]: poller.cpp:923: ===> command routes received
198Feb 11 05:46:37 sip_ua[477]: poller.cpp:923: ===> command conferences received
199Feb 11 05:46:37 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: Channel settings, ts=-1, flags=0001, data=0
200Feb 11 05:46:37 sip_ua[454]: fxs.cpp:434: ---> ts=255, state=Idle: Channel settings, ts=-1, flags=0001, data=0
201Feb 11 05:46:42 sip_ua[458]: regSyncAgent.cpp:842: connected to repro
202Feb 11 05:46:49 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=5
203Feb 11 05:46:49 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: CAS event, ts=254, flags=0000, data=5
204Feb 11 05:46:50 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialtone: DTMF detected, ts=254, flags=0000, data=2
205Feb 11 05:46:50 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone completed, ts=254, flags=0000, data=3
206Feb 11 05:46:50 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: Tone detected, ts=254, flags=0000, data=255
207Feb 11 05:46:51 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Dialing: DTMF detected, ts=254, flags=0000, data=2
208Feb 11 05:46:51 sip_ua[454]: fxs.cpp:275: ts 254: dialing 22
209Feb 11 05:46:51 sip_ua[454]: user_agent.cpp:3909: --> ua_dial_out() <sip:11@192.168.20.120> -> sip:22@192.168.20.120...
210Feb 11 05:46:51 sip_ua[659]: repro.cpp:658: doSessionAccounting(): Session Created 'branch=z9hG4bK254249088'
211Feb 11 05:46:51 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Tone detected, ts=254, flags=0000, data=255
212Feb 11 05:46:51 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 5 (Call is being processed!): cid=5, did=0, tid=9, rid=0, sid=0, nid=0
213Feb 11 05:47:23 sip_ua[478]: user_agent.cpp:2151: ---> transport 0: SIP event 9 (4xx received for Call!): cid=5, did=0, tid=9, rid=0, sid=0, nid=0
214Feb 11 05:47:23 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Calling: Call disconnected, ts=254, flags=0000, data=5
215Feb 11 05:47:23 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
216Feb 11 05:47:23 sip_ua[454]: comcerto.cpp:7146: ts 254: stopping RTP stream
217Feb 11 05:47:25 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Busy: CAS event, ts=254, flags=0000, data=13
218Feb 11 05:47:26 sip_ua[454]: fxs.cpp:434: ---> ts=254, state=Idle: CAS event, ts=254, flags=0000, data=13