All was working well but now the port is busy when called. With nothing connected it still gives busy. Any help would be appreciated.
10888965mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10888966mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=4 sub= calling=302 dir=out complete=0 ses=0
10888966mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=4 type=100 depth=1 nobar=1 setorig=1 ses=0
10888968mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPD0
10888968mS H323Evt: SetOperational 203 192.168.0.55:49154 to 0
10889213mS PRN: 302: Digit Pressed 4
10889214mS CMExtnRx: v=302, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
10889214mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10889214mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=44 sub= calling=302 dir=out complete=0 ses=0
10889215mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=44 type=100 depth=1 nobar=1 setorig=1 ses=0
10889434mS H323Evt: Recv: RegistrationRequest 192.168.0.155; Endpoints registered: 39; Endpoints in registration: 0
10889453mS PRN: 302: Digit Pressed 4
10889454mS CMExtnRx: v=302, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
10889454mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10889454mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=444 sub= calling=302 dir=out complete=0 ses=0
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=444 type=100 depth=1 nobar=1 setorig=1 ses=0
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Paging orig=1
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Paging depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=59 type=100 depth=2 nobar=1 setorig=0 ses=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: SYS SC: 59 2 sc=type=DialPaging code=59, num=416 callinfop->sending_complete=1 secondary_dialtone=
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=416 type=104 depth=3 nobar=1 setorig=0 ses=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Page orig=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Page depth=3 disallow_cw=0 dnd=0 real_call=0 group_call=0 type(CMNTypePaging) incl(0x8) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
10889457mS CMCallEvt: 0.1804.0 -1 BaseEP: NEW CMEndpoint f4f9f2b0 TOTAL NOW=7 CALL_LIST=3
10889457mS CMCallEvt: 0.1804.0 -1 PagingEP 416: StateChange: END=X CMCSIdle->CMCSDelete
10889457mS CMCallEvt: 0.1804.0 -1 BaseEP: DELETE CMEndpoint f4f9f2b0 TOTAL NOW=6 CALL_LIST=3
10889457mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Paging orig=0
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: CancelTimer CMTCDelayedProcessing
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: NO INITIAL TARGETS: Paging
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Paging depth=1 disallow_cw=0 dnd=0 real_call=0 group_call=0 type(CMNTypePaging) incl(0x48) excpt(0x48), allow_redir(1) remote=00000000 simult 0 (0)
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: Fallback() targeting failed
10889459mS CMLOGGING: CALL:2013/04/3011:41,00:00:00,000,302,O,444,444,TestPhone,,,1,,""n/a,0
10889459mS CD: CALL: 291.1802.0 BState=Idle Cut=0 Music=0.0 Aend="Test Phone(302)" (0.0) Bend="Paging(444)" [] (0.0) CalledNum=444 (Paging) CallingNum=302 (Test Phone) Internal=1 Time=952 AState=Dialling
10889459mS CD: CALL: 291.1802.0 Deleted
10889460mS CMExtnEvt: Test Phone: CALL LOST (CMCauseBusy)
10889460mS CMExtnEvt: Test Phone: Extn(302) Calling Party Number(302) Type(CMNTypeInternal)
10889460mS CMCallEvt: 291.1802.0 -1 Test Phone.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
10889460mS CMExtnEvt: v=41 State, new=CMESCompleted old=Dialling,0,0,Test Phone
10889461mS CMCallEvt: 0.1803.0 -1 BaseEP: DELETE CMEndpoint f4f82840 TOTAL NOW=5 CALL_LIST=2
10889461mS CMCallEvt: END CALL:249 (f4f83d0c)
10889463mS CMMap: PCG::AddToneGenerator g B1[4] for cp[625]b0r1 append pcp[627]b0r1 (total 1)
10889463mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[627]b0r1 Configure 0.0
10889463mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[627]b0r1 ConnectIndication 0.0
10889463mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPB1
10889463mS H323Evt: SetOperational 203 192.168.0.55:49154 to 1
10890083mS H323Evt: Recv: RegistrationRequest 192.168.0.136; Endpoints registered: 39; Endpoints in registration: 0
10890141mS H323Evt: RTP(50r): 192.168.0.55/49154 192.168.0.128/52912 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=1622 SENT=55 (avg size=160) RECV=50 (avg size=160)
10890157mS CMExtnEvt: Chantal: No user activity
10890198mS CMExtnEvt: Shipping: No user activity
10891427mS H323Evt: Recv: RegistrationRequest 192.168.0.171; Endpoints registered: 39; Endpoints in registration: 0
10891522mS CMExtnTx: v=302, p1=8040
CMFacility
Line: type=IPLine 250 Call: lid=291 id=41 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
10891523mS CMExtnRx: v=302, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
10891523mS CMCallEvt: 291.1802.0 -1 Test Phone.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
10891523mS CMExtnEvt: v=41 State, new=PortRecoverDelay old=CMESCompleted,0,0,Test Phone
10891524mS CMExtnEvt: Test Phone: CALL LOST (CMCauseForceClear)
10891524mS CMExtnEvt: Test Phone: Extn(302) Calling Party Number(302) Type(CMNTypeInternal)
10891524mS CMExtnEvt: Test Phone: CMExtnHandler::SetCurrent( id: 1802->0 )
10891525mS CMCallEvt: 291.1802.0 -1 Test Phone.-1: StateChange: END=X CMCSCompleted->CMCSDelete
10891526mS CMTARGET: 291.1802.0 -1 BaseEP: ~CMTargetHandler f4f800c0 ep f4f849c0
10891526mS CMCallEvt: 291.1802.0 -1 BaseEP: DELETE CMEndpoint f4f849c0 TOTAL NOW=4 CALL_LIST=2
10891528mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPB0
10891528mS H323Evt: SetOperational 203 192.168.0.55:49154 to 0
10891528mS H323Evt: RTP(END): 192.168.0.55/49154 192.168.0.128/52912 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=3009 SENT=125 RECV=119 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
10892466mS H323Evt: Recv: RegistrationRequest 192.168.0.180; Endpoints registered: 39; Endpoints in registration: 0
10888965mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10888966mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=4 sub= calling=302 dir=out complete=0 ses=0
10888966mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=4 type=100 depth=1 nobar=1 setorig=1 ses=0
10888968mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPD0
10888968mS H323Evt: SetOperational 203 192.168.0.55:49154 to 0
10889213mS PRN: 302: Digit Pressed 4
10889214mS CMExtnRx: v=302, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
10889214mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10889214mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=44 sub= calling=302 dir=out complete=0 ses=0
10889215mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=44 type=100 depth=1 nobar=1 setorig=1 ses=0
10889434mS H323Evt: Recv: RegistrationRequest 192.168.0.155; Endpoints registered: 39; Endpoints in registration: 0
10889453mS PRN: 302: Digit Pressed 4
10889454mS CMExtnRx: v=302, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
10889454mS CMTARGET: 291.1802.0 249 Test Phone.0: Setting Hard Timer 3000
10889454mS CMTARGET: 291.1802.0 249 Test Phone.0: LOOKUP CALL ROUTE: type=100 called_party=444 sub= calling=302 dir=out complete=0 ses=0
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=444 type=100 depth=1 nobar=1 setorig=1 ses=0
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Paging orig=1
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Paging depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
10889455mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=59 type=100 depth=2 nobar=1 setorig=0 ses=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: SYS SC: 59 2 sc=type=DialPaging code=59, num=416 callinfop->sending_complete=1 secondary_dialtone=
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD TARGET (N): number=416 type=104 depth=3 nobar=1 setorig=0 ses=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Page orig=0
10889456mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Page depth=3 disallow_cw=0 dnd=0 real_call=0 group_call=0 type(CMNTypePaging) incl(0x8) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0)
10889457mS CMCallEvt: 0.1804.0 -1 BaseEP: NEW CMEndpoint f4f9f2b0 TOTAL NOW=7 CALL_LIST=3
10889457mS CMCallEvt: 0.1804.0 -1 PagingEP 416: StateChange: END=X CMCSIdle->CMCSDelete
10889457mS CMCallEvt: 0.1804.0 -1 BaseEP: DELETE CMEndpoint f4f9f2b0 TOTAL NOW=6 CALL_LIST=3
10889457mS CMTARGET: 291.1802.0 249 Test Phone.0: SET USER: Paging orig=0
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: CancelTimer CMTCDelayedProcessing
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: NO INITIAL TARGETS: Paging
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: ADD USER: Paging depth=1 disallow_cw=0 dnd=0 real_call=0 group_call=0 type(CMNTypePaging) incl(0x48) excpt(0x48), allow_redir(1) remote=00000000 simult 0 (0)
10889458mS CMTARGET: 291.1802.0 249 Test Phone.0: Fallback() targeting failed
10889459mS CMLOGGING: CALL:2013/04/3011:41,00:00:00,000,302,O,444,444,TestPhone,,,1,,""n/a,0
10889459mS CD: CALL: 291.1802.0 BState=Idle Cut=0 Music=0.0 Aend="Test Phone(302)" (0.0) Bend="Paging(444)" [] (0.0) CalledNum=444 (Paging) CallingNum=302 (Test Phone) Internal=1 Time=952 AState=Dialling
10889459mS CD: CALL: 291.1802.0 Deleted
10889460mS CMExtnEvt: Test Phone: CALL LOST (CMCauseBusy)
10889460mS CMExtnEvt: Test Phone: Extn(302) Calling Party Number(302) Type(CMNTypeInternal)
10889460mS CMCallEvt: 291.1802.0 -1 Test Phone.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
10889460mS CMExtnEvt: v=41 State, new=CMESCompleted old=Dialling,0,0,Test Phone
10889461mS CMCallEvt: 0.1803.0 -1 BaseEP: DELETE CMEndpoint f4f82840 TOTAL NOW=5 CALL_LIST=2
10889461mS CMCallEvt: END CALL:249 (f4f83d0c)
10889463mS CMMap: PCG::AddToneGenerator g B1[4] for cp[625]b0r1 append pcp[627]b0r1 (total 1)
10889463mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[627]b0r1 Configure 0.0
10889463mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[627]b0r1 ConnectIndication 0.0
10889463mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPB1
10889463mS H323Evt: SetOperational 203 192.168.0.55:49154 to 1
10890083mS H323Evt: Recv: RegistrationRequest 192.168.0.136; Endpoints registered: 39; Endpoints in registration: 0
10890141mS H323Evt: RTP(50r): 192.168.0.55/49154 192.168.0.128/52912 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=1622 SENT=55 (avg size=160) RECV=50 (avg size=160)
10890157mS CMExtnEvt: Chantal: No user activity
10890198mS CMExtnEvt: Shipping: No user activity
10891427mS H323Evt: Recv: RegistrationRequest 192.168.0.171; Endpoints registered: 39; Endpoints in registration: 0
10891522mS CMExtnTx: v=302, p1=8040
CMFacility
Line: type=IPLine 250 Call: lid=291 id=41 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
10891523mS CMExtnRx: v=302, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=291 id=1802 in=0
10891523mS CMCallEvt: 291.1802.0 -1 Test Phone.0: StateChange: END=X CMCSCompletedTone->CMCSCompleted
10891523mS CMExtnEvt: v=41 State, new=PortRecoverDelay old=CMESCompleted,0,0,Test Phone
10891524mS CMExtnEvt: Test Phone: CALL LOST (CMCauseForceClear)
10891524mS CMExtnEvt: Test Phone: Extn(302) Calling Party Number(302) Type(CMNTypeInternal)
10891524mS CMExtnEvt: Test Phone: CMExtnHandler::SetCurrent( id: 1802->0 )
10891525mS CMCallEvt: 291.1802.0 -1 Test Phone.-1: StateChange: END=X CMCSCompleted->CMCSDelete
10891526mS CMTARGET: 291.1802.0 -1 BaseEP: ~CMTargetHandler f4f800c0 ep f4f849c0
10891526mS CMCallEvt: 291.1802.0 -1 BaseEP: DELETE CMEndpoint f4f849c0 TOTAL NOW=4 CALL_LIST=2
10891528mS CMMap: a=0.0 b=0.0 pcp[625]b0r1 RTPB0
10891528mS H323Evt: SetOperational 203 192.168.0.55:49154 to 0
10891528mS H323Evt: RTP(END): 192.168.0.55/49154 192.168.0.128/52912 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=3009 SENT=125 RECV=119 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
10892466mS H323Evt: Recv: RegistrationRequest 192.168.0.180; Endpoints registered: 39; Endpoints in registration: 0