Re:How to register Telepresence Profile 55 into CUCM8.6

iantra123 · ‎09-20-2012

Hello,

We try to registre a TPS 55 C40 with a CUCM using SIP but it can't.

Does anyone have succeed with it?

also how to add the Directory Server? How to configure so it can see the LDAP directory or can see the directory of the CUCM ?

Is there any link for help?

Regards,

antra

iantra123 · ‎09-21-2012

Hello,

For the registration, I've followed the document on administering TC Endpoint with CUCM8.6.

but the actual problem is that the registration is rejected

So what can I do so that it will be registered.

regards

Antra

Patrick Pettit · ‎09-21-2012

HI Antra - Have you loaded the cop.sgn file for the TC5.1.4 to the CUCM? Can you verify this?

Also, I'm assuming that your profile has touch panel? If you run the provisioning wizard from the touch panel, does it do the same?

VR

Patrick

iantra123 · ‎09-21-2012

Hello,

The result i've shown previously is a result from auto-Registration from the TPS.

i.e i've used the provisioning wizard from the touch pannel.

also i see in the touch pannel:

SIP URI=AUTO-REGISTER@IP_address_of_the_subscriber_CUCM.

Proxy=IP_address_of_the_subscriber_CUCM.

login :

password :

Patrick Pettit · ‎09-21-2012

Thanks. Is the cop.sgn file loaded as well?

VR

Patrick

iantra123 · ‎09-21-2012

Hello,

Why do I need the cop file if the datasheet says :

• Native registration with Cisco Unified Communications Manager (CUCM)

• Basic CUCM provisioning

• Firmware upgrade from CUCM

does it not already bundled with CUCM?

Antra

Patrick Pettit · ‎09-21-2012

I dont believe that cop.sgn file is bundled. Before I ask for any tracing to be done, I'd like for you to confirm its loaded in OS Administration if you can. I want to rule this out before proceeding.

VR

Patrick

iantra123 · ‎09-21-2012

OK,

Let me download the cop file from cisco and come back after.

Regards,

Antra

Danny De Ridder · ‎09-21-2012

To troubleshoot you can connect to the codec/profile as admin and do :

log ctx prov debug 9

log ctx sipreg debug 9

log output on

And you should see any sip registration attempts and provisioning debug.

Also, from admin shell you can do :

xstatus sip

xstatus provisioning

xstatus Provisioning

*s Provisioning Status: Provisioned

*s Provisioning Reason: ""

*s Provisioning Software UpgradeStatus SessionId: ""

*s Provisioning Software UpgradeStatus LastChange: "2012-09-20T06:40:04Z"

*s Provisioning Software UpgradeStatus Status: None

*s Provisioning Software UpgradeStatus Phase: None

*s Provisioning Software UpgradeStatus Message: ""

*s Provisioning Software UpgradeStatus VersionId: ""

*s Provisioning Software UpgradeStatus URL: ""

*s Provisioning Software Current VersionId: ""

*s Provisioning Software Current URL: ""

*s Provisioning Software Current CompletedAt: ""

*s Provisioning CUCM CAPF Mode: IgnoreAuth

*s Provisioning CUCM CAPF ServerName: "10.48.55.161"

*s Provisioning CUCM CAPF ServerPort: 3804

*s Provisioning CUCM CAPF LSC: Installed

*s Provisioning CUCM CAPF OperationState: NonPending

*s Provisioning CUCM CAPF OperationResult: CAPFLSCUpdated

*s Provisioning CUCM ProvisionSecurity: Signed

*s Provisioning CUCM CTL State: Installed

** end

OK

COP file tells CUCM the capabilities of the endpoints/profile.

iantra123 · ‎09-24-2012

Hello,

Thank you already for your answers.

I've checked in Cisco download and saw the file s52000tc5_1_4.pkg but i don't see the cop.sgn.

I've tried to unrar or uncopress it with 7Zip it but no success. any idea?

Second : This is the result of the auto-provisionning

xstatus SIP

*s SIP Proxy 1 Status: Active

*s SIP Proxy 1 Address: "10.10.40.2"

*s SIP Proxy 1 Secure: False

*s SIP Proxy 1 Verified: False

*s SIP Registration 1 Status: Failed

*s SIP Registration 1 Reason: "403 Forbidden"

*s SIP Registration 1 URI: "6019@10.10.40.2"

*s SIP Registration 1 Authentication: Off

*s SIP Profile 1 Proxy 1 Status: Active

*s SIP Profile 1 Proxy 1 Address: "10.10.40.2"

*s SIP Profile 1 Secure: False

*s SIP Profile 1 Verified: False

*s SIP Profile 1 Authentication: Off

*s SIP Profile 1 Registration 1 Status: Failed

*s SIP Profile 1 Registration 1 Reason: "403 Forbidden"

*s SIP Profile 1 Registration 1 URI: "6019@10.10.40.2"

** end

OK

xstatus provisioning

*s Provisioning Status: Idle

*s Provisioning Reason: ""

*s Provisioning Software UpgradeStatus SessionId: ""

*s Provisioning Software UpgradeStatus LastChange: ""

*s Provisioning Software UpgradeStatus Status:

*s Provisioning Software UpgradeStatus Phase:

*s Provisioning Software UpgradeStatus Message: ""

*s Provisioning Software UpgradeStatus VersionId: ""

*s Provisioning Software UpgradeStatus URL: ""

*s Provisioning Software Current VersionId: ""

*s Provisioning Software Current URL: ""

*s Provisioning Software Current CompletedAt: ""

** end

OK

Shoud I configure the user credential for this telepresence?

regards

Antra

iantra123 · ‎09-24-2012

Hello,

I've added the User Credential, then the CTS can register in the CUCM. fine.

But now : they can't make a call.

The CTS can see directory :OK,

But if I try to make a call then the call is disconnected directly

Any idea?

Regards,

Antra

Marius Nedregaard · ‎09-24-2012

Hi.

You do not need any COP installed to use TC software on the CUCM.

If you cant make calls, please make sure that the TC software on the endpoint is the newest one.

TC4.x and below are not supported by CUCM.

For troubleshooting:

1. Check software versions. Should be at 5.x

2. Check DNS settings on profile, to make sure the CUCM domain address is resolveable by the endpoint.

3. Check if you can see the IP for the system in CUCM. ( If there is no ip then endpoint is NOT SIP-registred to the CUCM - investigate that )

4. Make sure that the profile reports of no errors ( look at sip registration )

//Marius

Rafal Szeremeta · ‎09-24-2012

Hi Antra

That sounds like codec mismatch issue.

I would check your region settings and look in the logs for disconnection issue.

Cheers

Rafal

Patrick Pettit · ‎09-24-2012

Since it seems you have a DN now, I would check your Calling Search Space. When the call is placed maybe the ccm cant find the number your looking for since it may be in a different CSS. Also, if your calling over to VCS, I would check search history there to see if it was found or not.

VR

Patrick

Sent from Cisco Technical Support Android App

iantra123 · ‎09-24-2012

Hi all,

Firmware is TC5.1.3.292001

The CTS can be called, but can't make a call.

If I make a call from a 9971, voice gateway to the CTS, then the call pass.

but If I make a call from the CTS then the call cannot pass.

They (IPPhone, CTS, VoiceGateways) are in the same CSS, Location, Device-pool,Region, MRGL, MoH, ...

I've checked also in the SIP Profile, that cisco recomends, then see these parameters :

- redirect by application = yes

- use FQDN in SIP request = yes ==> I don't know if this is suitable for calls but cisco have given it in their site.

- Allow presentation sharing BFCP = yes

But still no success.

Below is a result of the debug from one CTS that try to make call to 6008

Regards,

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2012.09.24 16:44:30 =~=~=~=~=~=~=~=~=~=~=~=

1052.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1052.27 SipReg Starting work timer (outboundindex: 0).

1052.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1052.57 SipReg Stopping work timer because we have nothing more to do.

1062.27 SipReg Starting work timer (outboundindex: 0).

1062.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1062.57 SipReg Stopping work timer because we have nothing more to do.

!!! make a call

Command not recognized.

!!! make a call

Command not recognized.

!!! make a call

Command not recognized.

!!! make a call

Command not recognized.

!!! make a call to 1072.27 SipReg Starting work timer (outboundindex: 0).

61072.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1072.57 SipReg Calling SIPREG_doRegister to refresh registration.

1072.57 SipReg Setting status for regno 0 to 1, refresh=0 (cause: )

1072.57 SipReg Not informing SipDialog about re-registration

1072.57 SipReg Sending register request for sip:6019@10.10.40.2 to 10.10.40.2.

1072.57 SipReg Starting work timer (outboundindex: 0).

1072.58 SipReg Setting status for regno 0 to 2, refresh=90 (cause: )

1072.58 SipReg Sending registration status to stack and SipUa.

1072.58 SipReg Sending server status information upwards.

01072.87 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1072.87 SipReg Stopping work timer because we have nothing more to do.

08

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

!!! make a call to 6008

Command not recognized.

1082.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1082.27 SipReg Starting work timer (outboundindex: 0).

1082.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1082.57 SipReg Stopping work timer because we have nothing more to do.

1086.53 IXUser I: iXController(0x12d13380) registerProtocolUser: proto=1, user=0x12d60f58

1086.53 IXUser I: iXController(0x12d13380) registerProtocolUser: proto=2, user=0x12d31750

1086.56 MC I: Conference::addRemoteParticipant(p=5,ch=3) adding modecontrol shared channel

1086.56 MC I: Conference::modeChanged(c=4) holder=-1 Video (3): vid-off 0x0@0.0 0k

1086.56 MC I: RemoteParticipant::outgoingDuoModeChanged(p=5, ch=3): ModesController wants to run mode: Video (3): vid-off 0x0@0.0 0k

1086.57 CAMERA I: CamVisca::Ready_doCAMActionReq cameraId=1 actionId=1

1086.57 RTP I: SockPair::dynamic(sock=0) retrying next pair (rtp port=16414)

1086.57 RTP I: SockPair::dynamic(sock=1) retrying next pair (rtp port=16416)

1086.57 RTP I: SockPair::dynamic(sock=2) retrying next pair (rtp port=16418)

1086.57 RTP I: SockPair::dynamic(sock=3) retrying next pair (rtp port=16420)

1086.58 RTP I: SockPair::dynamic(sock=4) retrying next pair (rtp port=16422)

1086.58 CAMERA I: CamVisca::Ready_doCAMActionReq cameraId=1 actionId=19

1086.58 DATACTRL I: DataGateAddReq(ig=4) assoc gate -1 hdlc=yes

1086.66 MC I: MediaServerAction(): InputGate(ig=63) creation took 104 miliseconds

1086.66 MC I: MediaServerAction(): InputGate(ig=71) creation took 104 miliseconds

1086.68 CAMERA I: CamVisca::sendCAMPositionInd cameraId=1 pos=400/60/0/4577

1086.80 RTP I: TrafficCtrl: Added new entry (main): id: 32, tcid: 65568

1086.81 MC I: MediaServerAction(): InputGate(ig=66) creation took 251 miliseconds

1086.81 RTP I: TrafficCtrl: Added new entry (duo): id: 34, tcid: 65568

1086.83 MC I: MediaServerAction(): InputGate(ig=69) creation took 267 miliseconds

1086.83 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1086.83 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 0 w 1280 h 720

1086.84 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1086.84 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 8 w 1920 h 1080

1086.84 RTP I: TrafficCtrl: Added new entry (duo): id: 38, tcid: 65569

1086.87 MC I: CallParticipantImpl: calledUri: sip:6008@10.10.40.2

1086.87 SipCall I: ==== makeOutgoingCall appId=5, stackId=na

1086.88 MC I: RemoteParticipant::proposeMode(p=5, ch=1): Did not find mixer ((nil)) or channel (0x12d3185c)

1086.88 MC I: RemoteParticipant::propose60fpsThreshold(p=5, ch=1): Did not find mixer ((nil)) or channel (0x12d3185c)

1086.88 MC I: RemoteParticipant::reevalRefMode(p=5,ch=1) set ref [Audio (1): G.711a 64k ] q= auto, t60=6000

1086.89 MC I: RemoteParticipant::reevalRefMode(p=5,ch=2) set ref [Video (2): vid-off 0x0@0.0 0k ] q= auto, t60=6000

1086.89 MC I: RemoteParticipant::reevalRefMode(p=5,ch=3) set ref [Video (3): vid-off 0x0@0.0 0k ] q= auto, t60=6000

1086.89 MC I: RemoteParticipant::proposeMode(p=5, ch=4): Did not find mixer ((nil)) or channel (0x12d376ac)

1086.89 MC I: RemoteParticipant::propose60fpsThreshold(p=5, ch=4): Did not find mixer ((nil)) or channel (0x12d376ac)

1086.89 MC I: RemoteParticipant::reevalRefMode(p=5,ch=4) set ref [Data (4): H.224-HDLC 64k ] q= auto, t60=6000

1086.89 MC I: RemoteParticipant::reevalRefMode(p=5,ch=5) set ref [Video (5): vid-off 0x0@0.0 0k ] q= auto, t60=6000

1086.89 MC I: MediaServerAction(): InputGate(ig=73) creation took 330 miliseconds

1086.89 SipCall I: sip_call_handler::handleSIPMRtpLocalCapsetInd(5/-1/-1, capsetentries: 27): Received local capset with payload types set

1086.89 MC I: RemoteParticipant::configureIncomingChannel: Capability table:

1: (2) H.264 maxbr=6000000 maxfs=9000 maxmbps=245000 maxsmbps=245000 maxfps=6000 [97]

2: (2) H.264NIL maxbr=6000000 maxfs=9000 maxmbps=245000 maxsmbps=245000 maxfps=6000 [98]

3: (2) H.263PP maxbr=2000000 fps-qcif=3000 fps-cif=3000 fps-4cif=3000 fps-sif=3000 fps-4sif=3000 fps-vga=3000 fps-svga=3000 fps-xga=3000 fps-wcif=3000 fps-w4cif=1500 fps-w720=1000 fps-wxga=1000 [99]

4: (2) H.263 maxbr=2000000 fps-qcif=3000 fps-cif=3000 fps-4cif=3000 [34]

5: (2) H.261 maxbr=2000000 fps-qcif=3000 fps-cif=3000 [31]

6: (3) H.264 maxbr=6000000 maxfs=3840 maxmbps=108000 maxsmbps=108000 maxfps=6000 feat=Pres-Token [97]

7: (3) H.264NIL maxbr=6000000 maxfs=3840 maxmbps=108000 maxsmbps=108000 maxfps=6000 feat=Pres-Token [98]

8: (3) H.263PP maxbr=2000000 fps-qcif=3000 fps-cif=3000 fps-4cif=3000 fps-sif=3000 fps-4sif=3000 fps-vga=3000 fps-svga=3000 fps-xga=3000 fps-wcif=3000 fps-w4cif=1500 fps-w720=1000 fps-wxga=1000 feat=Pres-Token [99]

9: (3) H.263 maxbr=2000000 fps-qcif=3000 fps-cif=3000 fps-4cif=3000 feat=Pres-Token [34]

10: (3) H.261 maxbr=2000000 fps-qcif=3000 fps-cif=3000 feat=Pres-Token [31]

11: (1) AAC-LD rate=128k profile=25 [100]

12: (1) AAC-LD rate=64k profile=24 [102]

13: (1) AAC-LD rate=56k profile=24 [103]

14: (1) AAC-LD rate=48k profile=24 [104]

15: (1) G.722.1 rate=32k ptime(ms)=40 [105]

16: (1) G.722.1 rate=24k ptime(ms)=40 [106]

17: (1) G.722 rate=64k ptime(ms)=40 [9]

18: (1) G.711a rate=64k ptime(ms)=40 [8]

19: (1) G.711mu rate=64k ptime(ms)=40 [0]

20: (1) RTP-Dtmf [101]

21: (2) RTCP-Feedback-PLI

21: (2) RTCP-Feedback-FIR

21: (3) RTCP-Feedback-PLI

21: (3) RTCP-Feedback-FIR

22: (3) H.239Ctrl

23: (4) H.224-HDLC [107]

23: (4) H.224 [107]

Simcapset:

(1) {11,12,13,14,15,16,17,18,19,20}{1,2,3,4,5}{6,7,8,9,10}{23,23}

1086.91 VIDEOCTRL-0 I: (proxy,161) maximum number of framestorage slices 2

1086.91 DATACTRL I: DataGateCfgReq(ig=4) hdlc=yes

1086.94 MC I: VideoInputGateImpl::inputGateConfigState(ig=66): DecoderConfig[66]: maxMbps: 244980 maxWidth: 1920 maxHeight: 1200 memsize: 74432424

1086.95 SipStack I: SipDialog(ui=5,s=0) sendInviteRejToStack (403:Forbidden)

1086.95 MC W: Mcfsm::handleOtherMessages() unknown msg SIP_DialogFreed_Ind from SipStack

1086.95 SipCall I: sip_call_handler::handleSIPMCallRej(5/0/-1): Call rejected (cause: Forbidden)

1086.96 MC I: signalLocalReceiveCapsetChange, RemoteParticipant::signalLRCap

1086.96 SipCall I: ==== makeOutgoingUpdate appId=5, stackId=-1, eventCookie=-1, isEmptyCap=0, holdState=0

1086.96 MC I: RemoteParticipant::setRRRate(p=5): Received new remote rate from protocol: 0 (old: -1)

1086.96 MC I: RemoteParticipant::modeChanged(p=5, ch=1): ModesController wants to run mode: Audio (1): aud-off 0k

1086.96 SipCall I: ==== signalOutgoingModeChange appId=5, stackId=-1, eventCookie=-1, isEmptyCap=0, holdState=0

1086.97 MC I: configureOutgoingChannel(p=5, ch=1): Configuring mode : Audio (1): aud-off 0k (modescontroller says real is: Audio (1): aud-off 0k )

1086.97 MC I: RemoteParticipant::modeChanged(p=5, ch=2): ModesController wants to run mode: Video (2): vid-off 0x0@0.0 0k

1086.97 SipCall I: ==== signalOutgoingModeChange appId=5, stackId=-1, eventCookie=-1, isEmptyCap=0, holdState=0

1086.97 MC I: configureOutgoingChannel(p=5, ch=2): Configuring mode : Video (2): vid-off 0x0@0.0 0k (modescontroller says real is: Video (2): vid-off 0x0@0.0 0k )

1086.97 MC I: Delaying setting of media mode Video (2): vid-off 0x0@0.0 0k because the RTP addresses are missing.

1086.97 MC I: RemoteParticipant::modeChanged(p=5, ch=3): ModesController wants to run mode: Video (3): vid-off 0x0@0.0 0k

1086.97 MC I: Conference::calcCommonRate(c=4,ch=3) rate=0

1086.98 MC I: RemoteParticipant::modeChanged(p=5, ch=4): ModesController wants to run mode: Data (4): data-off 0k

1086.98 SipCall I: ==== signalOutgoingModeChange appId=5, stackId=-1, eventCookie=-1, isEmptyCap=0, holdState=0

1086.98 MC I: configureOutgoingChannel(p=5, ch=4): Configuring mode : Data (4): data-off 0k (modescontroller says real is: Data (4): data-off 0k )

1086.98 MC I: RemoteParticipant::modeChanged(p=5, ch=5): ModesController wants to run mode: Video (5): vid-off 0x0@0.0 0k

1086.98 SipCall I: ==== signalOutgoingModeChange appId=5, stackId=-1, eventCookie=-1, isEmptyCap=0, holdState=0

1086.98 MC I: configureOutgoingChannel(p=5, ch=5): Configuring mode : Video (5): vid-off 0x0@0.0 0k (modescontroller says real is: Video (5): vid-off 0x0@0.0 0k )

1086.99 MC I: Delaying setting of media mode Video (5): vid-off 0x0@0.0 0k because the RTP addresses are missing.

1086.99 MC !ER TransactionActionStorage::removeAction no match for Disconnect callstackid(5)

1086.99 SipMedia W: Got Update Req for non-existing call ID: 5

1087.01 DATACTRL I: DataGateCfgReq(ig=4) hdlc=yes

1087.01 MediaStreamController !ER MixerAudio::remove: wrong state

1087.02 MediaStreamController !ER MixerAudio::remove: wrong state

1087.04 IXUser I: iXController teardownIxChannel: Not connected

1087.05 MC I: CapabilityControllerImpl::setCapset() reduced = 0, waitForDuoGate = 0, hasLegacyVideo = 0

1087.06 RTP I: TrafficCtrl: Deleting entry (main): id: 32, tcid: 65568

1087.07 RTP I: TrafficCtrl: Deleting entry (duo): id: 34, tcid: 65568

1087.08 RTP I: TrafficCtrl: Deleting entry (duo): id: 38, tcid: 65569

1087.08 CAMERA I: CamVisca::Ready_doCAMActionReq cameraId=1 actionId=20

1087.08 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1087.09 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 0 w 1280 h 720

1087.09 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1087.09 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 8 w 1920 h 1080

1087.09 MC !ER TransactionActionStorage::removeAction no match for Modify InGate id(63)

1087.09 MC !ER TransactionActionStorage::removeAction no match for Modify InGate id(71)

1087.09 MediaServerMsctrl !ER Msf::createCnf(mx=65): No mixer

1087.10 MediaStreamController I: doAUDIOMIXERREMGATECNF(ms-ig=7) not found

1087.10 DATACTRL I: DataGateRemReq(ig=4)

1087.11 MediaStreamController !ER doVIDEOGATECONFIGURECNF(ms-ig=14) not found

1087.18 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1087.18 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 0 w 1280 h 720

1087.18 MediaStreamController I: MV::getVCSetting getOutputPortStatus initialized 1

1087.18 MediaStreamController I: MV::getVCSetting localHwCookieHint_ 8 w 1920 h 1080

1087.22 CAMERA I: CamVisca::sendCAMPositionInd cameraId=1 pos=400/60/0/4576

1087.53 MediaStreamController !ER doVIDEOGATECONFIGURECNF(ms-ig=14) not found

1087.53 MediaStreamController !ER doVIDEOMIXERADDCNF(ms-mx=9) not found

1087.53 MediaStreamController !ER doVIDEOMIXERADDCNF(ms-mx=10) not found

1087.57 VIDEOCTRL-0 I: (proxy,161) maximum number of framestorage slices 2

1087.60 VIDEOCTRL-0 I: VIDEOCTRL_doReadyMixerConfigureReq: redundant signal for mixer (rawvideo,1) from (MSCtrl,0)

1087.60 VIDEOCTRL-0 I: VIDEOCTRL_doReadyMixerConfigureReq: redundant signal for mixer (rawvideo,2) from (MSCtrl,0)

1087.61 MC I: MediaServerAction(): InputGate(ig=76) creation took 557 miliseconds

1087.75 CAMERA I: CamVisca::sendCAMPositionInd cameraId=1 pos=400/60/0/4577

1088.28 CAMERA I: CamVisca::sendCAMPositionInd cameraId=1 pos=400/60/0/4578

1092.27 SipReg Starting work timer (outboundindex: 0).

1092.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1092.57 SipReg Stopping work timer because we have nothing more to do.

1094.96 MC W: TransactionActionStorage::cbTimeoutAction: Modify InGate id(66)

1102.27 SipReg Starting work timer (outboundindex: 0).

1102.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1102.57 SipReg Stopping work timer because we have nothing more to do.

!!! nothing

Command not recognized.

!!! nothing

Command not recognized.

!!! nothing

Command not recognized.

!!! nothing

Command not recognized.

!!! nothing

Command not recognized.

!!! nothing

Command not recognized.

1112.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1112.27 SipReg Starting work timer (outboundindex: 0).

1112.57 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1112.58 SipReg Stopping work timer because we have nothing more to do.

1122.27 SipReg Starting work timer (outboundindex: 0).

1122.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1122.58 SipReg Stopping work timer because we have nothing more to do.

1132.28 SipReg Starting work timer (outboundindex: 0).

1132.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1132.58 SipReg Stopping work timer because we have nothing more to do.

1142.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1142.28 SipReg Starting work timer (outboundindex: 0).

1142.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1142.58 SipReg Stopping work timer because we have nothing more to do.

1152.28 SipReg Starting work timer (outboundindex: 0).

1152.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1152.58 SipReg Calling SIPREG_doRegister to refresh registration.

1152.58 SipReg Setting status for regno 0 to 1, refresh=0 (cause: )

1152.58 SipReg Not informing SipDialog about re-registration

1152.58 SipReg Sending register request for sip:6019@10.10.40.2 to 10.10.40.2.

1152.58 SipReg Starting work timer (outboundindex: 0).

1152.59 SipReg Setting status for regno 0 to 2, refresh=90 (cause: )

1152.59 SipReg Sending registration status to stack and SipUa.

1152.59 SipReg Sending server status information upwards.

1152.88 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1152.89 SipReg Stopping work timer because we have nothing more to do.

1162.28 SipReg Starting work timer (outboundindex: 0).

1162.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1162.58 SipReg Stopping work timer because we have nothing more to do.

1172.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1172.28 SipReg Starting work timer (outboundindex: 0).

1172.58 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1172.58 SipReg Stopping work timer because we have nothing more to do.

1182.28 SipReg Starting work timer (outboundindex: 0).

1182.59 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1182.59 SipReg Stopping work timer because we have nothing more to do.

1187.14 ServiceControl I: ServiceControl: restart request received

1187.14 PROV Provfsm: initiating de/re-registration triggered by CUCM service-control request!

1187.15 DnsLocator I: locatedAt() ipv4 unspec 10.10.40.2, port 536870911

1187.15 MWI I: on() subscribing to mailbox 1200@10.10.40.2 on behalf of sip:6019@10.10.40.2

1187.16 SipStack I: SipEv: Active Subscribe to '1200@10.10.40.2' of type 'message-summary', unsolicited=0

1187.16 SipStack I: SipEvNotify - Unsubsribing to sip:6019@localhost

1187.16 SipReg SipRegisterReq: Trying to register '' (iRegNum=0) on '10.10.40.2' (bWaitForNewConfig=0).

1187.16 SipReg Starting work timer (outboundindex: 0).

1187.16 SipReg SipRegisterReq: Trying to register '' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.16 SipReg Starting work timer (outboundindex: 1).

1187.16 SipReg SipRegisterReq: Trying to register '' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.17 SipReg Starting work timer (outboundindex: 2).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.17 SipReg Starting work timer (outboundindex: 3).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '10.10.40.2' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '10.10.40.2' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '10.10.40.2' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.17 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.17 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '10.10.40.2' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.18 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.18 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.21 MWI !ER reject() sip subscription failed. 489 Bad Event

1187.46 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1187.46 SipReg Calling SIPREG_doRegister in SIPREG_StateActivateNewValue upon SIP_REG_STATUS_REGISTERED.

1187.46 SipReg Setting status for regno 0 to 3, refresh=0 (cause: )

1187.47 SipReg Sending registration status to stack and SipUa.

1187.47 SipReg I: Sending deregister request for sip:6019@10.10.40.2 to 10.10.40.2.

1187.47 SipReg Starting work timer (outboundindex: 0).

1187.47 SipStack I: Failed to find new default outbound proxy at present time.

1187.49 SipReg Setting status for regno 0 to 0, refresh=0 (cause: )

1187.49 SipReg Sending registration status to stack and SipUa.

1187.49 SipReg Sending server status information upwards.

1187.49 MWI I: on() un-subscribing to unsolicited req URI sip:6019@localhost

1187.49 SipStack I: SipEvNotify - Unsubsribing to sip:6019@localhost

1187.50 SipStack I: SipEv: Active Subscribe to 'sip:6019@localhost' of type 'service-control', unsolicited=1

1187.50 SipStack !ER Failed to find flow for outbound proxy:

1187.50 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '10.10.40.2' (bWaitForNewConfig=0).

1187.50 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.50 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.51 SipReg Starting work timer (outboundindex: 1).

1187.51 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.51 SipReg Starting work timer (outboundindex: 2).

1187.51 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1187.51 SipReg Starting work timer (outboundindex: 3).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '10.10.40.2' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '10.10.40.2' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.51 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '10.10.40.2' (bWaitForNewConfig=0).

1187.51 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '10.10.40.2' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 0).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 1).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 2).

1187.52 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1187.52 SipReg Assuming work timer is actually started (outboundindex: 3).

1187.77 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1187.77 SipReg Calling SIPREG_doRegister in SIPREG_StateActivateNewValue upon SIP_REG_STATUS_INACTIVE.

1187.77 SipReg Setting status for regno 0 to 1, refresh=0 (cause: )

1187.77 SipReg Sending registration status to stack and SipUa.

1187.77 SipReg Sending register request for sip:6019@10.10.40.2 to 10.10.40.2.

1187.77 SipReg Starting work timer (outboundindex: 0).

1187.90 SipReg SipReg SIP Auth: received

1187.90 SipReg SipReg Number of Attempts=1 and Refresh timer=52

1187.90 SipReg Setting status for regno 0 to 4, refresh=52 (cause: 401 Unauthorized)

1187.90 SipReg Sending registration status to stack and SipUa.

1188.07 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1188.08 SipReg Stopping work timer because we have nothing more to do.

1192.29 SipReg Starting work timer (outboundindex: 0).

1192.59 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1192.59 SipReg Stopping work timer because we have nothing more to do.

1192.90 DnsLocator I: locate(): we've failed, give up for now

1192.90 DnsLocator I: locatedAt() ipv4 unspec 10.10.40.2, port 536870911

1192.90 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '10.10.40.2' (bWaitForNewConfig=0).

1192.91 SipReg Starting work timer (outboundindex: 0).

1192.91 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1192.91 SipReg Starting work timer (outboundindex: 1).

1192.91 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1192.91 SipReg Starting work timer (outboundindex: 2).

1192.91 SipReg SipRegisterReq: Trying to register 'sip:6019' (iRegNum=0) on '' (bWaitForNewConfig=0).

1192.91 SipReg Starting work timer (outboundindex: 3).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '10.10.40.2' (bWaitForNewConfig=0).

1192.91 SipReg Assuming work timer is actually started (outboundindex: 0).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1192.91 SipReg Assuming work timer is actually started (outboundindex: 1).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1192.91 SipReg Assuming work timer is actually started (outboundindex: 2).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=1) on '' (bWaitForNewConfig=0).

1192.91 SipReg Assuming work timer is actually started (outboundindex: 3).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '10.10.40.2' (bWaitForNewConfig=0).

1192.91 SipReg Assuming work timer is actually started (outboundindex: 0).

1192.91 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 1).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 2).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=2) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 3).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '10.10.40.2' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 0).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 1).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 2).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=3) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 3).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '10.10.40.2' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 0).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 1).

1192.92 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1192.92 SipReg Assuming work timer is actually started (outboundindex: 2).

1192.93 SipReg SipRegisterReq: Trying to register '' (iRegNum=4) on '' (bWaitForNewConfig=0).

1192.93 SipReg Assuming work timer is actually started (outboundindex: 3).

1193.21 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1193.21 SipReg Stopping work timer because we have nothing more to do.

1202.24 SipReg Scheduling new keep-alive for proxy 10.10.40.2 in 30 seconds.

1202.29 SipReg Starting work timer (outboundindex: 0).

1202.59 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1202.59 SipReg Stopping work timer because we have nothing more to do.

1212.29 SipReg Starting work timer (outboundindex: 0).

1212.59 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1212.59 SipReg Stopping work timer because we have nothing more to do.

1218.34 SipStack !ER Failed to find flow for outbound proxy:

1222.29 SipReg Starting work timer (outboundindex: 0).

1222.59 SipReg Executing SIPREG_Active_doSIPRegWorkTimeout.

1222.59 SipReg Stopping work timer because we have nothing more to do.

CUIL reboot request, restarting

Unknown message type: 4

tshell: Remote closed connection