waiting for line on pots 2

[Ludit]

Can someone point me to the right direction on which settings to look out for.

I have 2 combo cards (just installed 2nd card), 2 trunks on the first card and the rest are digital phones. I starting to get waiting on line error today when trying to dial out. Sometimes it would call out externally right away and sometimes it wouldn't connect at all with a waiting on line timer building up. No one is using the phones at the moment besides me.

Just a note,
The second combo card trunks are all lit up green like there are pot lines but it doesn't. This combo card model is V2 like the first one, can confirm its the same model number but when I checked the settings at the Control Unit, the second combo card is not listed as V2 while the other one is. Wonder if its supposed to be like this.

 

[Ludit]

I uninstalled the second combo card and now all the ds phones are dialing out at the 7 sec mark while waiting for line.

 

[Ludit]

Trying to see if there is anything I can find,

19:35:24 1045885mS CMARS: FORM: Main - Received Number:
19:35:24 1045885mS CMARS: CMARSTargetingPending
19:35:24 1045885mS CMCallEvt: 0.1037.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
19:35:24 1045886mS CMCallEvt: 0.1037.0 -1 BaseEP: DELETE CMEndpoint f5743f5c TOTAL NOW=2 CALL_LIST=1
19:35:24 1045886mS CD: CALL: 0.1036.0 BState=Idle Cut=2 Music=3.0 Aend="Extn203(203)" (0.1) Bend="" [] (0.0) CalledNum=9 () CallingNum=203 (Extn203) Internal=1 Time=3295 AState=Dialling
19:35:24 1045887mS CMMap: a=0.1 b=0.0 D1
19:35:25 1046167mS PRN: 203: Digit Pressed 1
19:35:25 1046167mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
19:35:25 1046168mS CMARS: FORM: Main - Received Number: 1
19:35:25 1046168mS CMARS: CMARSTargetingPending
19:35:25 1046169mS CMMap: a=0.1 b=0.0 D0
19:35:25 1046816mS PRN: 203: Digit Pressed 9
19:35:25 1046817mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
19:35:25 1046817mS CMARS: FORM: Main - Received Number: 19
19:35:25 1046817mS CMARS: CMARSTargetingPending
19:35:26 1047104mS PRN: 203: Digit Pressed 1
19:35:26 1047104mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
19:35:26 1047104mS CMARS: FORM: Main - Received Number: 191
19:35:26 1047105mS CMARS: CMARSTargetingPending
19:35:26 1047379mS PRN: 203: Digit Pressed 7
19:35:26 1047379mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
19:35:26 1047380mS CMARS: FORM: Main - Received Number: 1917
19:35:26 1047380mS CMARS: CMARSTargetingPending
19:35:26 1047642mS PRN: 203: Digit Pressed 3
19:35:26 1047642mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
19:35:26 1047642mS CMARS: FORM: Main - Received Number: 19173
19:35:26 1047643mS CMARS: CMARSTargetingPending
19:35:26 1047787mS RES: Thu 20/7/2017 19:35:26 FreeMem=54782660 54461060(1) CachedMem=321600 CMMsg=4(5) Buff=5200 1357 1000 7463 5 Links=7618 BTree=14594 CPU=04.60% CPUStats=1/6/9084/18298/19743/0/0 MCR=0 MCW=0
19:35:26 1047788mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=14(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:35:26 1047788mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
19:35:26 1047879mS PRN: 203: Digit Pressed 5
19:35:26 1047879mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
19:35:26 1047880mS CMARS: FORM: Main - Received Number: 191735
19:35:26 1047880mS CMARS: CMARSTargetingPending
19:35:27 1048104mS PRN: 203: Digit Pressed 3
19:35:27 1048104mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
19:35:27 1048104mS CMARS: FORM: Main - Received Number: 1917353
19:35:27 1048105mS CMARS: CMARSTargetingPending
19:35:27 1048404mS PRN: 203: Digit Pressed 0
19:35:27 1048404mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
19:35:27 1048405mS CMARS: FORM: Main - Received Number: 19173530
19:35:27 1048405mS CMARS: CMARSTargetingPending
19:35:27 1048679mS PRN: 203: Digit Pressed 3
19:35:27 1048679mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
19:35:27 1048680mS CMARS: FORM: Main - Received Number: 191735303
19:35:27 1048680mS CMARS: CMARSTargetingPending
19:35:27 1048929mS PRN: 203: Digit Pressed 6
19:35:27 1048929mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
19:35:27 1048929mS CMARS: FORM: Main - Received Number: 1917353036
19:35:27 1048930mS CMARS: CMARSTargetingPending
19:35:28 1049154mS PRN: 203: Digit Pressed 9
19:35:28 1049154mS CMExtnRx: v=203, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
19:35:28 1049155mS CMARS: FORM: Main - Received Number: 19173530369
19:35:28 1049155mS CMARS: CMARSTargetingPending
19:35:31 1052881mS CMARS: Case 1 - dial_delay_time: 3000 has expired - Resolve what we might have - Called Number: 19173530369 Addr: f4e544c8
19:35:31 1052881mS CMARS: FORM: Main - Received Number: 19173530369
19:35:31 1052881mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 19173530369 - Line Group Id: 0
19:35:31 1052881mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
19:35:31 1052881mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
19:35:31 1052882mS CMCallEvt: 0.1039.0 -1 BaseEP: NEW CMEndpoint f4e51e20 TOTAL NOW=3 CALL_LIST=1
19:35:31 1052883mS CMARS: FOUND LINE - Line Id: 4 - using line group id: 0 - Called Number: 19173530369 - Calling Number: 203
19:35:31 1052883mS CMARS: SEND Setup TO LINE
19:35:31 1052884mS CMCallEvt: 0.1039.0 9 Alog Trunk:4: StateChange: END=child CMCSIdle->CMCSOffering
19:35:31 1052884mS CMLineTx: v=4
CMSetup
Line: type=AnalogueLine 4 Call: lid=0 id=1039 in=0
Called[19173530369] Type=Default (100) Reason=CMDRdirect SndComp Calling[203] Type=Internal Plan=Default
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn203
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn203
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=203
IE CMIEDeviceDetail (231) c0a801640000040c LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=78 number=1 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1036 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
IE CMIEMohSourceId (247) MOH Source = 255
Locale: enu
19:35:31 1052884mS CD: CALL: 0.1036.0 BState=Idle Cut=2 Music=0.0 Aend="Extn203(203)" (0.1) Bend="" [Line 4] (0.0) CalledNum=919173530369 () CallingNum=203 (Extn203) Internal=1 Time=10293 AState=Dialling
19:35:32 1053288mS RES: Thu 20/7/2017 19:35:32 FreeMem=54775276 54461060(1) CachedMem=314216 CMMsg=4(5) Buff=5200 1359 1000 7463 5 Links=7614 BTree=14593 CPU=03.12% CPUStats=1/3/9084/18899/19743/0/0 MCR=0 MCW=0
19:35:32 1053288mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=14(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:35:32 1053288mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
19:35:35 1056884mS CD: CALL: 0.1036.0 BState=Idle Cut=2 Music=0.0 Aend="Extn203(203)" (0.1) Bend="" [Line 4] (0.0) CalledNum=919173530369 () CallingNum=203 (Extn203) Internal=1 Time=14293 AState=Dialling
19:35:36 1057891mS CMLineRx: v=4
CMReleaseComp
Line: type=AnalogueLine 4 Call: lid=0 id=1039 in=0
BChan: slot=1 chan=4
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a801640000040f LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=4 channel=0 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1039 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:36 1057891mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
19:35:36 1057892mS CMCallEvt: 0.1039.0 -1 Alog Trunk:4: StateChange: END=X CMCSOffering->CMCSDelete
19:35:36 1057892mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
19:35:36 1057893mS CMCallEvt: 0.1038.0 9 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
19:35:36 1057893mS CMCallEvt: 0.1036.0 9 Extn203.0: StateChange: END=A CMCSDialling->CMCSRingBack
19:35:36 1057893mS CMExtnEvt: v=1 State, new=Alerting old=Dialling,0,0,Extn203
19:35:36 1057894mS CMExtnTx: v=203, p1=0
CMProgress
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 20/07/17 19:35
19:35:36 1057894mS CMCallEvt: 0.1039.0 -1 BaseEP: DELETE CMEndpoint f4e51e20 TOTAL NOW=2 CALL_LIST=1
19:35:36 1057894mS CD: CALL: 0.1036.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn203(203)" (0.1) Bend="" [] (0.0) CalledNum=919173530369 () CallingNum=203 (Extn203) Internal=1 Time=15303 AState=Ringing
19:35:36 1057896mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
19:35:36 1057896mS CMMap: a=21.11 b=1.255 T
19:35:36 1057896mS CMMap: PCG::MapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0 type CGTypeDTMF
19:35:36 1057896mS CMMap: a=21.11 b=0.1 M22
19:35:36 1057896mS CMMap: DTMF:layTones g T[32] ,EEEEE
19:35:36 1057897mS CMMap: Started playing tones, cp[74]b1r0 (from g null)
19:35:36 1057897mS CMMap: cp[74]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
19:35:37 1058288mS RES: Thu 20/7/2017 19:35:37 FreeMem=54780692 54461060(1) CachedMem=319632 CMMsg=4(5) Buff=5200 1358 1000 7463 5 Links=7609 BTree=14593 CPU=03.45% CPUStats=1/3/9084/18889/19743/0/1 MCR=0 MCW=0
19:35:37 1058288mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=14(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:35:37 1058288mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
19:35:37 1058892mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
19:35:38 1059717mS CMMap: PCG::UnmapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0
19:35:38 1059717mS CMMap: a=21.11 b=0.1 M02
19:35:38 1059717mS CMMap: a=21.11 b=0.0 T0
19:35:38 1059718mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown resource busy 1, total 45
19:35:38 1059719mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
19:35:38 1059719mS CMMap: a=21.11 b=1.255 T
19:35:38 1059719mS CMMap: PCG::MapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0 type CGTypeDTMF
19:35:38 1059719mS CMMap: a=21.11 b=0.1 M22
19:35:38 1059719mS CMMap: DTMF:layTones g T[33] ,EEEEE
19:35:38 1059719mS CMMap: Started playing tones, cp[74]b1r0 (from g null)
19:35:38 1059719mS CMMap: cp[74]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
19:35:40 1061540mS CMMap: PCG::UnmapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0
19:35:40 1061540mS CMMap: a=21.11 b=0.1 M02
19:35:40 1061540mS CMMap: a=21.11 b=0.0 T0
19:35:40 1061541mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown resource busy 1, total 45
19:35:43 1064892mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
19:35:43 1064892mS CMARS: Target: Short_Code: 1N; - Line_Group_ID: 0 - Telephone_Number: 1N - Line_Status: CMARS_OUTOFSERVICE
19:35:43 1064893mS CMCallEvt: 0.1040.0 -1 BaseEP: NEW CMEndpoint f4e51e20 TOTAL NOW=3 CALL_LIST=1
19:35:43 1064894mS CMARS: FOUND LINE - Line Id: 3 - using line group id: 0 - Short Code: 1N; - Called Number: 19173530369 - Calling Number: 203
19:35:43 1064894mS CMARS: Retarget result=SEND Setup TO LINE
19:35:43 1064894mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=child CMCSIdle->CMCSOffering
19:35:43 1064895mS CMLineTx: v=3
CMSetup
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
Called[19173530369] Type=Default (100) Reason=CMDRdirect SndComp Calling[203] Type=Internal Plan=Default
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Extn203
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn203
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=203
IE CMIEDeviceDetail (231) c0a801640000040c LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=78 number=1 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1036 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
IE CMIEMohSourceId (247) MOH Source = 255
Locale: enu
19:35:43 1064895mS CD: CALL: 0.1036.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn203(203)" (0.1) Bend="" [Line 3] (0.0) CalledNum=919173530369 () CallingNum=203 (Extn203) Internal=1 Time=22304 AState=Ringing
19:35:43 1064897mS CMMap: a=21.11 b=0.0 DTMF::AllocateRAS allocated CMRTVoicemail resource busy 2, total 45
19:35:43 1064897mS CMMap: a=21.11 b=1.255 T
19:35:43 1064897mS CMMap: PCG::MapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0 type CGTypeDTMF
19:35:43 1064897mS CMMap: a=21.11 b=0.1 M22
19:35:43 1064897mS CMMap: DTMF:layTones g T[34] ,EEEEE
19:35:43 1064897mS CMMap: Started playing tones, cp[74]b1r0 (from g null)
19:35:43 1064898mS CMMap: cp[74]b1r0 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
19:35:43 1064941mS CMLineRx: v=3
CMSetupAck
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=1 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x1 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:43 1064941mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSAccept
19:35:43 1064941mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
19:35:43 1064941mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.1038.0 9 ARS for Main - Call State: CMCSAccept
19:35:43 1064941mS CMCallEvt: 0.1038.0 -1 ARS for Main: StateChange: END=X CMCSAccept->CMCSDelete
19:35:43 1064942mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=B CMCSOffering->CMCSOverlapRecv
19:35:43 1064942mS CMCallEvt: 0.1038.0 -1 BaseEP: DELETE CMEndpoint f4e544c8 TOTAL NOW=2 CALL_LIST=1
19:35:43 1064943mS CMLineRx: v=3
CMProceeding
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=1 chan=3
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:43 1064943mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=B CMCSOverlapRecv->CMCSAccept
19:35:43 1064944mS CMExtnTx: v=203, p1=0
CMProceeding
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
Timed: 20/07/17 19:35
19:35:43 1064944mS CD: CALL: 0.1036.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn203(203)" (0.1) Bend="Line 3" [Line 3] (1.3) CalledNum=19173530369 () CallingNum=203 (Extn203) Internal=0 Time=22353 AState=Ringing
19:35:43 1064945mS CMMap: PCG::UnmapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5686ba0
19:35:43 1064945mS CMMap: a=21.11 b=0.1 M02
19:35:43 1064946mS CMMap: a=21.11 b=0.0 T0
19:35:43 1064946mS CMMap: a=21.11 b=0.0 DTMF::~DTMF freed CMRTUnknown resource busy 1, total 45
19:35:43 1064946mS CMMap: PCG::MapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b 0 type CGTypeSimple
19:35:43 1064947mS CMMap: PCG::MapBChan pcp[70]b1r0 cp_b f5662490 other_cp_b f4ff866c type CGTypeSimple
19:35:43 1064947mS CMMap: a=0.1 b=1.3 M12
19:35:43 1064947mS CMMap: PCG::MapBChan pcp[74]b1r0 cp_b f4ff866c other_cp_b f5662490 type CGTypeSimple
19:35:43 1064947mS CMMap: a=1.3 b=0.1 M12
19:35:44 1065288mS RES: Thu 20/7/2017 19:35:44 FreeMem=54785452 54461060(1) CachedMem=324392 CMMsg=4(5) Buff=5200 1358 1000 7463 5 Links=7612 BTree=14593 CPU=04.35% CPUStats=1/3/9084/18996/19743/0/1 MCR=0 MCW=0
19:35:44 1065288mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=51 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=14(TLS=3) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:35:44 1065288mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
19:35:47 1068895mS CD: CALL: 0.1036.0 BState=Ringing Cut=3 Music=0.0 Aend="Extn203(203)" (0.1) Bend="Line 3" [Line 3] (1.3) CalledNum=19173530369 () CallingNum=203 (Extn203) Internal=0 Time=26304 AState=Ringing
19:35:48 1069721mS CMLineRx: v=3
CMProgress
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=1 chan=3
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:48 1069721mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=B CMCSAccept->CMCSRinging
19:35:48 1069722mS CMExtnTx: v=203, p1=0
CMProgress
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDNotISDN (1)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
Timed: 20/07/17 19:35
19:35:48 1069722mS CMLineRx: v=3
CMAlerting
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=1 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:48 1069722mS CMLineRx: v=3
CMConnect
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=1 chan=3
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
19:35:48 1069723mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=B CMCSRinging->CMCSConnReq
19:35:48 1069723mS CMCallEvt: 0.1036.0 9 Extn203.0: StateChange: END=A CMCSRingBack->CMCSOGConnReq
19:35:48 1069723mS CMExtnEvt: v=1 State, new=Connected old=Alerting,0,0,Extn203
19:35:48 1069723mS CMExtnTx: v=203, p1=0
CMConnect
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=919173530369
IE CMIEDeviceDetail (231) c0a8016400000410 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=3 channel=0 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1040 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
Timed: 20/07/17 19:35
19:35:48 1069723mS CMExtnRx: v=203, p1=0
CMConnectAck
Line: type=DigitalExtn 2 Call: lid=0 id=1036 in=0
19:35:48 1069723mS CMCallEvt: 0.1036.0 9 Extn203.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
19:35:48 1069724mS CMTARGET: 0.1036.0 9 Extn203.0: ~CMTargetHandler f4e57f68 ep f4e5e820
19:35:48 1069724mS CMCallEvt: 0.1040.0 9 Alog Trunk:3: StateChange: END=B CMCSConnReq->CMCSConnected
19:35:48 1069725mS CMLineTx: v=3
CMConnectAck
Line: type=AnalogueLine 3 Call: lid=0 id=1040 in=0
BChan: slot=0 chan=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Extn203
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=203
IE CMIEDeviceDetail (231) c0a801640000040c LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=78 number=1 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1036 ipaddr=192.168.1.100 apps=0 loc=0 em_loc=1 features2=0x0 is_spcall=0
19:35:48 1069725mS CD: CALL: 0.1036.0 BState=Connected Cut=3 Music=0.0 Aend="Extn203(203)" (0.1) Bend="Line 3" [Line 3] (1.3) CalledNum=19173530369 () CallingNum=203 (Extn203) Internal=0 Time=27134 AState=Connected

 

[TouchToneTommy]

Program ALL trunk ports that do not have a dial tone connected as "Not in Service" and a different outgoing line group from the working trunks

 

[Ludit]

Thans for the reply.
I tried that previous but it had no effect but I was able to get it working. Thought maybe there was something wrong with the main line so I switched it with the fax line and dial the main line so I can get to the fax (switched at blocks). Dialed and was normal so then I turned off the ip500, switched it back and got it working with no more waiting for line. Weird.

Anyways I turned off the trunks also like you suggested . Thanks

 

[TouchToneTommy]

If you are patching your lines into the IP 500 with 6C telephone line cords instead of 8C patch cords, it's possible that the plug went in crooked and didn't make contact in the socket.

V2 Combo cards will automatically take a line out of service if it doesn't detect battery voltage on a line, V1's will not.

 

[ipohead]

As an aside to this I am not clear about trunk utilisation.

On an idle system will the first call be placed on line 1 and if that is still active the next call will be placed on line 2?

 

[Telecomboy]

For outbound with no line appearances it will grab lines in a circular fashion so even if line 1 is idle the system may grab line 2 for outbound.

 

[Ludit]

Thanks for the extra info.
The 2nd combo card was indeed V1 while its labeled and purchased as a V2 model.