V3.1 hangs up on me when I try to record a greeting

[matthias]

Just starting out with 3cx .
I am running "I test mode on a VM"
Voice Quality is good but every time I get to the recording prompt 3cx drops the call.
Any ideas?

 

[iang]

Hi Matthias

Can you post any logs from 3cx? What softphone or hardphone are you using to call the 3cx's voicemail?

 

[matthias]

x-lite , 3cx softphone and Linksys 942 doe not seem to make a difference
I'll look for the logs and upload....

 

[matthias]

I assuem teh not found message is a hint ?

14:57:58.235|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8976 timestamp=3082180
14:57:58.255|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8977 timestamp=3082180
14:57:58.275|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8978 timestamp=3082180
14:57:58.295|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8979 timestamp=3082180
14:57:58.315|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8980 timestamp=3082180
14:57:58.315|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8980 timestamp=3082180
14:57:58.315|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=8980 timestamp=3082180
14:57:58.365|MediaServer.cpp(604)|Trace5||??:EP 00000020@. Stop to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\toexit_presspnd.wav
14:57:58.446|MediaServer.cpp(607)|Trace5||??:EP 00000020@. RTP stream is stopped
14:57:58.446|MediaServer.cpp(509)|Trace5||??:EP 00000020@. RTP stream is resumed
14:57:58.446|MediaServer.cpp(604)|Trace5||??:EP 00000020@. Stop to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\toexit_presspnd.wav
14:57:58.446|MediaServer.cpp(607)|Trace5||??:EP 00000020@. RTP stream is stopped
14:57:58.446|MediaServer.cpp(509)|Trace5||??:EP 00000020@. RTP stream is resumed
14:57:58.486|MediaServer.cpp(643)|Trace5||??:EP [email protected] to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\OPTMNU.mp3
14:58:00.639|MSEndPoint.cpp(566)|Trace5||??:
RTCP packet len=176:
V=2
P=0
RC=1
type=200
length=12
(length+1)*4=52
sender Report:
SSRC=715984741
NTP=3405027179.625
sender RTP timestamp=3102340
Sender packets count=1070
Sender octet sent=172808

14:58:03.623|MSEndPoint.cpp(566)|Trace5||??:
RTCP packet len=176:
V=2
P=0
RC=1
type=200
length=12
(length+1)*4=52
sender Report:
SSRC=715984741
NTP=3405027182.686
sender RTP timestamp=3126820
Sender packets count=1223
Sender octet sent=199124

14:58:06.988|MSEndPoint.cpp(566)|Trace5||??:
RTCP packet len=176:
V=2
P=0
RC=1
type=200
length=12
(length+1)*4=52
sender Report:
SSRC=715984741
NTP=3405027185.750
sender RTP timestamp=3151300
Sender packets count=1376
Sender octet sent=225440

14:58:09.942|MSEndPoint.cpp(566)|Trace5||??:
RTCP packet len=176:
V=2
P=0
RC=1
type=200
length=12
(length+1)*4=52
sender Report:
SSRC=715984741
NTP=3405027188.811
sender RTP timestamp=3175780
Sender packets count=1529
Sender octet sent=251756

14:58:10.483|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
14:58:10.483|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
14:58:10.483|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
14:58:10.493|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9586 timestamp=3180260
14:58:10.503|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9587 timestamp=3180260
14:58:10.523|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9588 timestamp=3180260
14:58:10.543|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9589 timestamp=3180260
14:58:10.563|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9590 timestamp=3180260
14:58:10.563|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9591 timestamp=3180260
14:58:10.593|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9592 timestamp=3180260
14:58:10.593|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9592 timestamp=3180260
14:58:10.593|MSEndPoint.cpp(733)|Trace5||??TMF detected on EP:00000020@payload #101 seqNo=9592 timestamp=3180260
14:58:10.613|MediaServer.cpp(604)|Trace5||??:EP 00000020@. Stop to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\OPTMNU.mp3
14:58:10.623|MediaServer.cpp(607)|Trace5||??:EP 00000020@. RTP stream is stopped
14:58:10.623|MediaServer.cpp(509)|Trace5||??:EP 00000020@. RTP stream is resumed
14:58:10.903|MediaServer.cpp(604)|Trace5||??:EP 00000020@. Stop to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\OPTMNU.mp3
14:58:10.903|MediaServer.cpp(607)|Trace5||??:EP 00000020@. RTP stream is stopped
14:58:10.903|MediaServer.cpp(509)|Trace5||??:EP 00000020@. RTP stream is resumed
14:58:11.054|MediaServer.cpp(643)|Trace5||??:EP [email protected] to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\record_your.wav
14:58:11.625|MSCallConf.cpp(353)|Trace5||??layed File on 00000020@ closed
14:58:11.625|MediaServer.cpp(604)|Trace5||??:EP 00000020@. Stop to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\record_your.wav
14:58:11.625|MediaServer.cpp(607)|Trace5||??:EP 00000020@. RTP stream is stopped
14:58:11.635|MediaServer.cpp(643)|Trace5||??:EP [email protected] to play file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Prompts\Sets\8210986B-9412-497f-AD77-3A554F4A9BDB\greeting_message.wav
14:58:11.635|MediaServer.cpp(854)|Trace5||??:EndPoint 00000020@ removed from call 24
14:58:11.695|MSEndPoint.cpp(566)|Trace5||??:
RTCP packet len=160:
V=2
P=0
RC=0
type=200
length=6
(length+1)*4=28
sender Report:
SSRC=715984741
NTP=3405027190.608
sender RTP timestamp=3190180
Sender packets count=1619
Sender octet sent=265988

14:58:12.075|MediaServer.cpp(714)|Trace5||??:EP00000020@ start to record file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Temp\ivr\RWVafaa5f.tmp
14:58:12.075|MediaServer.cpp(678)|Trace5||??:EP 00000020@. Stop to record file C:\Program Files\3CX PhoneSystem\Bin\..\Data\Ivr\Temp\ivr\RWVafaa5f.tmp
14:58:12.105|MediaServer.cpp(821)|Trace5||??:EP 00000020@ not found in call 24
14:58:12.105|MediaServer.cpp(882)|Trace5||??:references to call 24 were removed
14:58:12.105|MSCallConf.cpp(31)|Trace5||??:Call: 24 destroyed
14:58:12.105|MediaServer.cpp(579)|Trace5||??:references to EndPoint 00000020@ were removed
14:58:12.105|RTPReceiver.cpp(141)|Trace5||??:Endpoint for socket 800 is not found!
18:21:37.325|MSEndPoint.cpp(331)|Trace5||??:xptemplate addr(0)=192.168.0.200
18:21:37.325|MSEndPoint.cpp(344)|Trace5||??:EP 00000021@: choose IP address - 192.168.0.200
18:21:37.335|MediaServer.cpp(223)|Trace5||??:EndPoint created:
EndPoint: ID=00000021@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7064
RTCP:192.168.0.200:7065
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
NOT SET
101:telephony-event
Party ptime:20
Party RTP:0.0.0.0:0
Party RTCP:0.0.0.0:0
Decoders:
<empty>

18:21:37.376|MediaServer.cpp(772)|Trace5||??:EP 00000021@ joined to call 25
18:21:37.376|MediaServer.cpp(781)|Trace5||??:starting send on new call 25
18:21:37.376|MediaServer.cpp(470)|Trace5||??:EP 00000021@. RTP stream is stopped
18:21:37.396|MediaServer.cpp(854)|Trace5||??:EndPoint 00000021@ removed from call 25
18:21:37.396|MediaServer.cpp(821)|Trace5||??:EP 00000021@ not found in call 25
18:21:37.396|MediaServer.cpp(882)|Trace5||??:references to call 25 were removed
18:21:37.396|MSCallConf.cpp(31)|Trace5||??:Call: 25 destroyed
18:21:37.396|MediaServer.cpp(579)|Trace5||??:references to EndPoint 00000021@ were removed
18:21:37.396|RTPReceiver.cpp(141)|Trace5||??:Endpoint for socket 800 is not found!
18:21:48.552|MSEndPoint.cpp(331)|Trace5||??:xptemplate addr(0)=192.168.0.200
18:21:48.552|MSEndPoint.cpp(344)|Trace5||??:EP 00000022@: choose IP address - 192.168.0.200
18:21:48.562|MediaServer.cpp(223)|Trace5||??:EndPoint created:
EndPoint: ID=00000022@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7066
RTCP:192.168.0.200:7067
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
NOT SET
101:telephony-event
Party ptime:20
Party RTP:0.0.0.0:0
Party RTCP:0.0.0.0:0
Decoders:
<empty>

18:21:48.562|MediaServer.cpp(772)|Trace5||??:EP 00000022@ joined to call 26
18:21:48.562|MediaServer.cpp(781)|Trace5||??:starting send on new call 26
18:21:48.562|MediaServer.cpp(470)|Trace5||??:EP 00000022@. RTP stream is stopped
18:21:48.592|MediaServer.cpp(854)|Trace5||??:EndPoint 00000022@ removed from call 26
18:21:48.592|MediaServer.cpp(821)|Trace5||??:EP 00000022@ not found in call 26
18:21:48.592|MediaServer.cpp(882)|Trace5||??:references to call 26 were removed
18:21:48.592|MSCallConf.cpp(31)|Trace5||??:Call: 26 destroyed
18:21:48.592|MediaServer.cpp(579)|Trace5||??:references to EndPoint 00000022@ were removed
18:21:48.592|RTPReceiver.cpp(141)|Trace5||??:Endpoint for socket 800 is not found!
18:21:58.546|MSEndPoint.cpp(331)|Trace5||??:xptemplate addr(0)=192.168.0.200
18:21:58.546|MSEndPoint.cpp(344)|Trace5||??:EP 00000023@: choose IP address - 192.168.0.200
18:21:58.556|MediaServer.cpp(223)|Trace5||??:EndPoint created:
EndPoint: ID=00000023@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7068
RTCP:192.168.0.200:7069
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
NOT SET
101:telephony-event
Party ptime:20
Party RTP:0.0.0.0:0
Party RTCP:0.0.0.0:0
Decoders:
<empty>

18:21:58.576|MediaServer.cpp(772)|Trace5||??:EP 00000023@ joined to call 27
18:21:58.576|MediaServer.cpp(781)|Trace5||??:starting send on new call 27
18:21:58.586|MediaServer.cpp(470)|Trace5||??:EP 00000023@. RTP stream is stopped
18:21:58.586|MSEndPoint.cpp(331)|Trace5||??:xptemplate addr(0)=192.168.0.200
18:21:58.586|MSEndPoint.cpp(344)|Trace5||??:EP 00000024@: choose IP address - 192.168.0.200
18:21:58.586|MediaServer.cpp(223)|Trace5||??:EndPoint created:
EndPoint: ID=00000024@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7070
RTCP:192.168.0.200:7071
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
NOT SET
101:telephony-event
Party ptime:20
Party RTP:0.0.0.0:0
Party RTCP:0.0.0.0:0
Decoders:
<empty>

18:21:58.596|MediaServer.cpp(772)|Trace5||??:EP 00000024@ joined to call 27
18:21:58.596|MediaServer.cpp(470)|Trace5||??:EP 00000024@. RTP stream is stopped
18:21:58.596|MediaServer.cpp(412)|Trace5||??arty changed on Endpoint
EndPoint: ID=00000023@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7068
RTCP:192.168.0.200:7069
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
0CMU
101:telephony-event
Party ptime:30
Party RTP:192.168.0.2:16450
Party RTCP:192.168.0.2:16451
Decoders:
0CMU
8CMA
101:telephony-event

18:22:06.487|MediaServer.cpp(412)|Trace5||??arty changed on Endpoint
EndPoint: ID=00000024@(LOCAL)
Status: MSEP_LOCAL
RTP:192.168.0.200:7070
RTCP:192.168.0.200:7071
STUN RTP:0.0.0.0:0
STUN RTCP:0.0.0.0:0
Coder:
0CMU
101:telephony-event
Party ptime:20
Party RTP:192.168.0.225:55046
Party RTCP:192.168.0.225:55047
Decoders:
0CMU
3:GSM
8CMA
13:CN
99:SPEEX
110:iLBC
101:telephony-event

18:22:38.553|MediaServer.cpp(854)|Trace5||??:EndPoint 00000023@ removed from call 27
18:22:38.553|MediaServer.cpp(854)|Trace5||??:EndPoint 00000024@ removed from call 27
18:22:38.553|MediaServer.cpp(821)|Trace5||??:EP 00000024@ not found in call 27
18:22:38.553|MediaServer.cpp(579)|Trace5||??:references to EndPoint 00000024@ were removed
18:22:38.553|RTPReceiver.cpp(141)|Trace5||??:Endpoint for socket 816 is not found!
18:22:38.563|MediaServer.cpp(821)|Trace5||??:EP 00000023@ not found in call 27
18:22:38.563|MediaServer.cpp(882)|Trace5||??:references to call 27 were removed
18:22:38.563|MSCallConf.cpp(31)|Trace5||??:Call: 27 destroyed
18:22:38.563|MediaServer.cpp(579)|Trace5||??:references to EndPoint 00000023@ were removed
18:22:38.563|RTPReceiver.cpp(141)|Trace5||??:Endpoint for socket 800 is not found!

 

[matthias]

3xdPhone system.trace

09:02:25.858|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:02:27.140|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=1112, status=0)
09:02:27.140|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2000, status=0)
09:02:27.150|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2001, status=0)
09:03:25.904|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:04:25.860|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:05:25.817|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:06:25.863|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:07:25.819|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:08:25.886|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:09:03.490|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:09:03.500|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:09:03.500|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=8971cb111e6e790a cseq=REGISTER [email protected]:45568 / 26 from(wire)
09:09:03.500|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:09:03.500|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:09:03.500|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:09:03.500|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=8971cb111e6e790a cseq=REGISTER [email protected]:45568 / 26 from(wire)
09:09:03.500|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:09:03.590|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:09:03.590|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:09:03.590|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:09:25.862|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:09:27.214|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:09:27.214|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:09:27.214|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=4b686b7670041304 cseq=REGISTER [email protected]:45568 / 27 from(wire)
09:09:27.214|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:09:27.214|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:09:27.214|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:09:27.214|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=4b686b7670041304 cseq=REGISTER [email protected]:45568 / 27 from(wire)
09:09:27.224|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:09:27.224|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:09:27.224|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:09:27.234|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:10:25.868|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:10:30.365|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:10:30.365|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:10:30.365|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=8903dd21f748a036 cseq=REGISTER [email protected]:45568 / 28 from(wire)
09:10:30.365|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:10:30.365|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:10:30.365|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:10:30.365|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=8903dd21f748a036 cseq=REGISTER [email protected]:45568 / 28 from(wire)
09:10:30.365|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:10:30.375|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:10:30.375|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:10:30.375|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:11:05.245|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:11:05.255|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:11:05.255|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=ae475f18ad3aea32 cseq=REGISTER [email protected]:45568 / 29 from(wire)
09:11:05.255|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:11:05.255|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:11:05.255|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:11:05.255|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=ae475f18ad3aea32 cseq=REGISTER [email protected]:45568 / 29 from(wire)
09:11:05.265|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:11:05.275|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:11:05.275|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:11:05.275|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:11:25.844|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:12:00.474|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:12:00.474|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:12:00.474|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=7c2f6a26ba6eb312 cseq=REGISTER [email protected]:45568 / 30 from(wire)
09:12:00.474|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:12:00.474|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:12:00.474|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:12:00.474|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=7c2f6a26ba6eb312 cseq=REGISTER [email protected]:45568 / 30 from(wire)
09:12:00.474|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:12:00.494|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:12:00.494|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:12:00.494|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:12:12.802|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:12:12.802|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:12:12.802|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=a72beb08f45ba931 cseq=REGISTER [email protected]:45568 / 31 from(wire)
09:12:12.802|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:12:12.802|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:12:12.802|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:12:12.802|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=a72beb08f45ba931 cseq=REGISTER [email protected]:45568 / 31 from(wire)
09:12:12.802|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:12:12.812|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:12:12.812|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:12:12.812|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:12:25.841|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:12:29.326|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=1112, status=0)
09:12:29.356|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2000, status=0)
09:12:29.386|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2001, status=0)
09:13:25.887|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:14:25.843|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:15:25.900|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:16:04.295|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:16:04.295|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:16:04.295|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=b86e10330d26760c cseq=REGISTER [email protected]:45568 / 32 from(wire)
09:16:04.295|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:16:04.295|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:16:04.295|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:16:04.295|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=b86e10330d26760c cseq=REGISTER [email protected]:45568 / 32 from(wire)
09:16:04.305|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:16:04.315|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:16:04.315|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:16:04.315|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:16:25.906|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:16:28.970|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:16:28.980|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:16:28.980|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=e5691b306062c447 cseq=REGISTER [email protected]:45568 / 33 from(wire)
09:16:28.980|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:16:28.980|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:16:28.980|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:16:28.980|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=e5691b306062c447 cseq=REGISTER [email protected]:45568 / 33 from(wire)
09:16:28.980|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:16:28.990|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:16:28.990|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:16:28.990|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:17:01.347|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
09:17:01.447|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
09:17:25.872|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:18:25.898|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:19:25.855|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:20:25.911|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:21:25.897|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:22:25.833|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:22:31.802|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=1112, status=0)
09:22:31.802|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2000, status=0)
09:22:31.812|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2001, status=0)
09:23:25.890|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:24:25.846|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:25:25.892|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:25:26.513|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:25:26.513|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:25:26.513|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=7865823ca00fb363 cseq=REGISTER [email protected]:45568 / 34 from(wire)
09:25:26.513|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:25:26.513|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:25:26.513|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:25:26.513|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=7865823ca00fb363 cseq=REGISTER [email protected]:45568 / 34 from(wire)
09:25:26.513|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:25:26.533|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:25:26.533|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:25:26.543|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:25:35.967|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:25:35.977|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840501147:04a231d51cf416880d2fc787057ad705
09:25:35.987|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=637fbb00c164b878 cseq=REGISTER [email protected]:45568 / 35 from(wire)
09:25:35.987|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:25:35.987|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:25:35.987|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=2455e77d has been authenticated successfully
09:25:35.987|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=637fbb00c164b878 cseq=REGISTER [email protected]:45568 / 35 from(wire)
09:25:35.987|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:45568;rinstance=a153743e3540bf1f>
09:25:35.987|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
09:25:35.997|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:25:35.997|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:26:25.869|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:27:25.875|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:28:25.861|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:29:25.817|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:30:25.864|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:31:25.840|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:32:25.896|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:32:34.088|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=1112, status=0)
09:32:34.088|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2000, status=0)
09:32:34.088|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2001, status=0)
09:33:25.862|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:34:25.899|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:35:25.895|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:36:25.871|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:37:01.733|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
09:37:01.823|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
09:37:03.155|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 1112 @ 3CXPhoneSystem
09:37:03.165|Helper.cxx(865)|Trace5|Resip|>>: username=1112 password=1112 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840540539:a48341190096b23aa74f4639512bb118
09:37:03.165|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=615f1802387cef4a cseq=REGISTER [email protected]:8250 / 14 from(wire)
09:37:03.165|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:37:03.165|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:37:03.165|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "bci Home Office"<sip:[email protected]>;tag=31207a1d has been authenticated successfully
09:37:03.165|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=615f1802387cef4a cseq=REGISTER [email protected]:8250 / 14 from(wire)
09:37:03.165|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:8250;rinstance=e923b6ce96f7739d>
09:37:03.245|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:8250;rinstance=e923b6ce96f7739d;expires in 3600 sec.
09:37:03.245|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:37:03.255|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 1112
09:37:25.888|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:38:25.854|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:39:25.840|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:40:25.886|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:41:25.833|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:42:25.929|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:42:36.664|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=1112, status=0)
09:42:36.674|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2000, status=0)
09:42:36.674|.\CAHandler.cpp(252)|Trace5||CAHandler::setStatus:[CM102005] CA request setStatus(dn=2001, status=0)
09:42:55.732|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2001 @ 3CXPhoneSystem
09:42:55.732|Helper.cxx(865)|Trace5|Resip|>>: username=2001 password=2001 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840511941:8056fe8e647f67a25e8d318a4786df41
09:42:55.732|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=-ae0c1a7c cseq=REGISTER [email protected]:5060 / 26784 from(wire)
09:42:55.732|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:42:55.732|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:42:55.732|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "2001 Test Extension"<sip:[email protected]>;tag=a645e06c4b5cf0fco0 has been authenticated successfully
09:42:55.742|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=-ae0c1a7c cseq=REGISTER [email protected]:5060 / 26784 from(wire)
09:42:55.742|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> "2001 Test Extension"<sip:[email protected]:5060>;expires=3600
09:42:55.752|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:5060;expires in 3600 sec.
09:42:55.752|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:42:55.752|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2001
09:43:25.855|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:44:25.912|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:45:25.848|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:46:25.904|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:47:25.830|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:48:25.817|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
09:48:41.509|ServerAuthManager.cxx(363)|Trace5|Resip|>>:Sending challenge to SipReq: REGISTER 192.168.0.200 tid=ce4996103a382a04 cseq=REGISTER [email protected]:44254 / 1 from(wire)
09:48:41.509|ServerAuthManager.cxx(41)|Trace5|Resip|>>:ServerAuth challenged request SipReq: REGISTER 192.168.0.200 tid=ce4996103a382a04 cseq=REGISTER [email protected]:44254 / 1 from(wire)
09:48:41.900|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:48:41.900|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=REGISTER uri=sip:192.168.0.200 nonce=12840580121:c37152c9cf3ed199e767df823f3651e3
09:48:41.900|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: REGISTER 192.168.0.200 tid=d14b135b971d841a cseq=REGISTER [email protected]:44254 / 2 from(wire)
09:48:41.900|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:48:41.900|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:48:41.900|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=df28412d has been authenticated successfully
09:48:41.900|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: REGISTER 192.168.0.200 tid=d14b135b971d841a cseq=REGISTER [email protected]:44254 / 2 from(wire)
09:48:41.910|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:[email protected] -> <sip:[email protected]:44254;rinstance=6cc479e5ed49a57e>
09:48:41.930|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:44254;rinstance=6cc479e5ed49a57e;expires in 3600 sec.
09:48:41.960|.\Registrar.cpp(116)|Trace5|Registrar|ServRegs:nAdd:Got registration from Ext.2000;
Contacts: [<sip:[email protected]:44254;rinstance=6cc479e5ed49a57e>]
09:48:41.960|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: OnHook; DN 2000
09:48:41.960|.\Registrar.cpp(141)|Log2|Registrar|ServRegs:nAdd:[CM113002] Registered: Ext.2000
09:48:41.960|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:[email protected]
09:48:41.960|.\Registrar.cpp(98)|Trace5||VoiceBoxForceNotify::execute:Forced out of dialog NOTIFY for DN 2000
09:48:42.240|ServerAuthManager.cxx(363)|Trace5|Resip|>>:Sending challenge to SipReq: SUBSCRIBE [email protected] tid=df08971fea451843 cseq=SUBSCRIBE [email protected]:44254 / 1 from(wire)
09:48:42.240|ServerAuthManager.cxx(41)|Trace5|Resip|>>:ServerAuth challenged request SipReq: SUBSCRIBE [email protected] tid=df08971fea451843 cseq=SUBSCRIBE [email protected]:44254 / 1 from(wire)
09:48:42.450|ServerAuthManager.cxx(307)|Trace5|Resip|>>:Requesting credential for 2000 @ 3CXPhoneSystem
09:48:42.450|Helper.cxx(865)|Trace5|Resip|>>: username=2000 password=2000 realm=3CXPhoneSystem method=SUBSCRIBE uri=sip:[email protected] nonce=12840580122:4227d05e9d58bc3476a5dab9a394c61d
09:48:42.450|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: SUBSCRIBE [email protected] tid=17722077d41b3170 cseq=SUBSCRIBE [email protected]:44254 / 2 from(wire)
09:48:42.450|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
09:48:42.450|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
09:48:42.450|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:nAuthSuccess:Caller "3cx at Home"<sip:[email protected]>;tag=4b7c0a73 has been authenticated successfully
09:48:42.450|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: SUBSCRIBE [email protected] tid=17722077d41b3170 cseq=SUBSCRIBE [email protected]:44254 / 2 from(wire)
09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(188)|Trace5|MessageSubscription|MessageSubscribeHandler:nNewSubscriptionnNewSubscription(h=385)
09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(189)|Trace5|MessageSubscription|MessageSubscribeHandler:nNewSubscription:msg:SUBSCRIBE sip:[email protected] SIP/2.0
Via: SIP/2.0/UDP 192.168.0.99:44254;branch=z9hG4bK-d87543-17722077d41b3170-1--d87543-;rport=44254
Max-Forwards: 70
Contact: <sip:[email protected]:44254>
To: "3cx at Home"<sip:[email protected]>
From: "3cx at Home"<sip:[email protected]>;tag=4b7c0a73
Call-ID: YzNkYTlhZjg3OGY5ZDRiMGFhYjcyYzQxYzMxZjdjZjI.
CSeq: 2 SUBSCRIBE
Expires: 300
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Proxy-Authorization: Digest username="2000",realm="3CXPhoneSystem",nonce="12840580122:4227d05e9d58bc3476a5dab9a394c61d",uri="sip:[email protected]",response="9a06f14039e06784904455ba6724246f",algorithm=MD5
User-Agent: X-Lite release 1011s stamp 41150
Event: message-summary
Content-Length: 0


09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(162)|Trace5|MessageSubscription|MessageSubscribeHandler:nReadyToSendnReadyToSend
09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(222)|Trace5|MessageSubscription|MessageSubscribeHandler:nTerminatednTerminated(hSub=385)
2007/11/27 >>
08:11:41.129|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.229|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.289|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.289|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.289|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:8250;rinstance=e923b6ce96f7739d
08:11:41.289|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:[email protected] has expired
08:11:41.299|.\Extension.cpp(69)|Trace5|Endpoints|Extension::Extension:Extension Ext.1112 is not registered!
08:11:41.299|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Undefined; DN 1112
08:11:41.309|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.329|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:45568;rinstance=a153743e3540bf1f
08:11:41.389|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.399|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.399|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
08:11:41.519|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.569|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.569|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:44254;rinstance=6cc479e5ed49a57e
08:11:41.629|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:[email protected] has expired
08:11:41.639|.\Extension.cpp(69)|Trace5|Endpoints|Extension::Extension:Extension Ext.2000 is not registered!
08:11:41.639|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Undefined; DN 2000
08:11:41.639|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.669|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.679|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:41.679|.\StunClient.cpp(79)|Trace5|STUN|StunClient:rocess:STUN response received from 64.69.76.23
08:11:41.840|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.850|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:[email protected]; contact=sip:[email protected]:5060
08:11:41.850|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:[email protected] has expired
08:11:41.860|.\Extension.cpp(69)|Trace5|Endpoints|Extension::Extension:Extension Ext.2001 is not registered!
08:11:41.860|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Undefined; DN 2001
08:11:41.860|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:[email protected]
08:11:41.950|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:42.050|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message
08:11:42.150|TuSelector.cxx(70)|Trace5|Resip|>>:Stats message