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

Discussion in '3CX Phone System - General' started by matthias, Nov 26, 2007.

  1. matthias

    Joined:
    Nov 26, 2007
    Messages:
    4
    Likes Received:
    0
    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?
     
  2. iang

    Joined:
    Nov 18, 2007
    Messages:
    13
    Likes Received:
    0
    Hi Matthias

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

    Joined:
    Nov 26, 2007
    Messages:
    4
    Likes Received:
    0
    x-lite , 3cx softphone and Linksys 942 doe not seem to make a difference
    I'll look for the logs and upload....
     
  4. matthias

    Joined:
    Nov 26, 2007
    Messages:
    4
    Likes Received:
    0
    I assuem teh not found message is a hint ?

    14:57:58.235|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8976 timestamp=3082180
    14:57:58.255|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8977 timestamp=3082180
    14:57:58.275|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8978 timestamp=3082180
    14:57:58.295|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8979 timestamp=3082180
    14:57:58.315|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8980 timestamp=3082180
    14:57:58.315|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=8980 timestamp=3082180
    14:57:58.315|MSEndPoint.cpp(733)|Trace5||??:DTMF 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 00000020@.Start 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||??:DTMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
    14:58:10.483|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
    14:58:10.483|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9585 timestamp=3180260
    14:58:10.493|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9586 timestamp=3180260
    14:58:10.503|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9587 timestamp=3180260
    14:58:10.523|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9588 timestamp=3180260
    14:58:10.543|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9589 timestamp=3180260
    14:58:10.563|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9590 timestamp=3180260
    14:58:10.563|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9591 timestamp=3180260
    14:58:10.593|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9592 timestamp=3180260
    14:58:10.593|MSEndPoint.cpp(733)|Trace5||??:DTMF detected on EP:00000020@payload #101 seqNo=9592 timestamp=3180260
    14:58:10.593|MSEndPoint.cpp(733)|Trace5||??:DTMF 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 00000020@.Start 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||??:played 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 00000020@.Start 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||??:party 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:
    0:pCMU
    101:telephony-event
    Party ptime:30
    Party RTP:192.168.0.2:16450
    Party RTCP:192.168.0.2:16451
    Decoders:
    0:pCMU
    8:pCMA
    101:telephony-event

    18:22:06.487|MediaServer.cpp(412)|Trace5||??:party 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:
    0:pCMU
    101:telephony-event
    Party ptime:20
    Party RTP:192.168.0.225:55046
    Party RTCP:192.168.0.225:55047
    Decoders:
    0:pCMU
    3:GSM
    8:pCMA
    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!
     
  5. matthias

    Joined:
    Nov 26, 2007
    Messages:
    4
    Likes Received:
    0
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 26 from(wire)
    09:09:03.500|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:09:03.590|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:09:03.590|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 27 from(wire)
    09:09:27.224|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:09:27.224|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:09:27.224|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 28 from(wire)
    09:10:30.365|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:10:30.375|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:10:30.375|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 29 from(wire)
    09:11:05.265|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:11:05.275|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:11:05.275|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 30 from(wire)
    09:12:00.474|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:12:00.494|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:12:00.494|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 31 from(wire)
    09:12:12.802|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:12:12.812|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:12:12.812|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 32 from(wire)
    09:16:04.305|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:16:04.315|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:16:04.315|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 33 from(wire)
    09:16:28.980|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:16:28.990|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:16:28.990|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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::process:STUN response received from 64.69.76.23
    09:17:01.447|.\StunClient.cpp(79)|Trace5|STUN|StunClient::process: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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 34 from(wire)
    09:25:26.513|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:25:26.533|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:25:26.533|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:45568 / 35 from(wire)
    09:25:35.987|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f>
    09:25:35.987|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:45568;rinstance=a153743e3540bf1f;expires in 3600 sec.
    09:25:35.997|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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::process:STUN response received from 64.69.76.23
    09:37:01.823|.\StunClient.cpp(79)|Trace5|STUN|StunClient::process: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 contact=1112@192.168.0.225: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::eek:nAuthSuccess:Caller "bci Home Office"<sip:1112@192.168.0.200>;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 contact=1112@192.168.0.225:8250 / 14 from(wire)
    09:37:03.165|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:1112@192.168.0.200 -> <sip:1112@192.168.0.225:8250;rinstance=e923b6ce96f7739d>
    09:37:03.245|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:1112@192.168.0.200; contact=sip:1112@192.168.0.225:8250;rinstance=e923b6ce96f7739d;expires in 3600 sec.
    09:37:03.245|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:1112@192.168.0.200
    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 contact=2001@192.168.0.2: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::eek:nAuthSuccess:Caller "2001 Test Extension"<sip:2001@192.168.0.200>;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 contact=2001@192.168.0.2:5060 / 26784 from(wire)
    09:42:55.742|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2001@192.168.0.200 -> "2001 Test Extension"<sip:2001@192.168.0.2:5060>;expires=3600
    09:42:55.752|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2001@192.168.0.200; contact=sip:2001@192.168.0.2:5060;expires in 3600 sec.
    09:42:55.752|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2001@192.168.0.200
    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 contact=2000@192.168.0.99: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 contact=2000@192.168.0.99: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 contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;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 contact=2000@192.168.0.99:44254 / 2 from(wire)
    09:48:41.910|ServerRegistration.cxx(214)|Trace5|Resip|>>:Adding sip:2000@192.168.0.200 -> <sip:2000@192.168.0.99:44254;rinstance=6cc479e5ed49a57e>
    09:48:41.930|.\Registrar.cpp(669)|Trace5|Registrar|RegistrarDB::updateContact:Updated registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99:44254;rinstance=6cc479e5ed49a57e;expires in 3600 sec.
    09:48:41.960|.\Registrar.cpp(116)|Trace5|Registrar|ServRegs::eek:nAdd:Got registration from Ext.2000;
    Contacts: [<sip:2000@192.168.0.99: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::eek:nAdd:[CM113002] Registered: Ext.2000
    09:48:41.960|ServerRegistration.cxx(42)|Trace5|Resip|>>:accepted a registration sip:2000@192.168.0.200
    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 2000@192.168.0.200 tid=df08971fea451843 cseq=SUBSCRIBE contact=2000@192.168.0.99:44254 / 1 from(wire)
    09:48:42.240|ServerAuthManager.cxx(41)|Trace5|Resip|>>:ServerAuth challenged request SipReq: SUBSCRIBE 2000@192.168.0.200 tid=df08971fea451843 cseq=SUBSCRIBE contact=2000@192.168.0.99: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:2000@192.168.0.200 nonce=12840580122:4227d05e9d58bc3476a5dab9a394c61d
    09:48:42.450|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: SUBSCRIBE 2000@192.168.0.200 tid=17722077d41b3170 cseq=SUBSCRIBE contact=2000@192.168.0.99: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::eek:nAuthSuccess:Caller "3cx at Home"<sip:2000@192.168.0.200>;tag=4b7c0a73 has been authenticated successfully
    09:48:42.450|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: SUBSCRIBE 2000@192.168.0.200 tid=17722077d41b3170 cseq=SUBSCRIBE contact=2000@192.168.0.99:44254 / 2 from(wire)
    09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(188)|Trace5|MessageSubscription|MessageSubscribeHandler::eek:nNewSubscription:eek:nNewSubscription(h=385)
    09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(189)|Trace5|MessageSubscription|MessageSubscribeHandler::eek:nNewSubscription:msg:SUBSCRIBE sip:2000@192.168.0.200 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:2000@192.168.0.99:44254>
    To: "3cx at Home"<sip:2000@192.168.0.200>
    From: "3cx at Home"<sip:2000@192.168.0.200>;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:2000@192.168.0.200",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::eek:nReadyToSend:eek:nReadyToSend
    09:48:42.450|c:\svn\src\pbxserver\ServerSubscr.h(222)|Trace5|MessageSubscription|MessageSubscribeHandler::eek:nTerminated:eek:nTerminated(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:1112@192.168.0.200
    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:1112@192.168.0.200; contact=sip:1112@192.168.0.225:8250;rinstance=e923b6ce96f7739d
    08:11:41.289|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:1112@192.168.0.200 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:1112@192.168.0.200
    08:11:41.329|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:2000@192.168.0.200; contact=sip:2000@192.168.0.99: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::process:STUN response received from 64.69.76.23
    08:11:41.519|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:2000@192.168.0.200
    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:2000@192.168.0.200; contact=sip:2000@192.168.0.99:44254;rinstance=6cc479e5ed49a57e
    08:11:41.629|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:2000@192.168.0.200 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:2000@192.168.0.200
    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::process:STUN response received from 64.69.76.23
    08:11:41.840|.\Registrar.cpp(647)|Trace5|Registrar|RegistrarDB::removeAor:Removed registrar entry: aor=sip:2001@192.168.0.200
    08:11:41.850|.\Registrar.cpp(684)|Trace5|Registrar|RegistrarDB::removeContact:Removed registrar contact: aor=sip:2001@192.168.0.200; contact=sip:2001@192.168.0.2:5060
    08:11:41.850|.\Registrar.cpp(42)|Log2|Registrar|ServRegs::checkExpiration:[CM113000] Registration for sip:2001@192.168.0.200 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:2001@192.168.0.200
    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
     

Share This Page