SCN Broken

[Rappy]

I have 2 ip office systems that used to have SCN enabled. Since a few nights ago, they can inter office dial US but we cannot dial THEM!

Remote side- ip office 406v2 ver 3.2.69- ip address on 192.168.239.x/24 subnet
Main Site- ip office 412 ver 3.2.69- ip address on 192.168.1.x/24 subnet

A few nights ago, i moved the ip office on the remote side to its own VLAN because the number of collisions it was causing on our network was slowing the user traffic. It was moved successfully. I then changed the IP trunk line to point to the new IP address of the remote side from the primary site. I can ping the 406 from the main site, i can manage the 406 from the PC running manager on the main site, and they can interoffice dial US, but cannot dial them. I have deleted the ip trunk and recreated it at the main site; i have also deleted the ip route from the main site to the remote site. I added a shortcode to be able to dial them, and i was able to restore functionality of interoffice dialing. HOWEVER- i cannot see the called party's caller ID information when i call them, but they can see my caller id info when they call me! the calls in question are from donkey kong to ext 313 and bugsy seagal to ext 325. I am attaching a trace below:


475418mS RES: Thu 14/4/2011 08:36:34 FreeMem=13130680(9) CMMsg=5 (6) Buff=200 850 1000 1310 5 Links=10861 Elements=154
475534mS AVRIP Tx: v=192.168.15.12 send=49 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
475535mS AVRIP Tx: v=192.168.239.32 send=0 receive=0
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
479500mS PRN: AVRIP receive from pbx c0a8ef20 count = 0
485534mS AVRIP Tx: v=192.168.15.12 send=50 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
485535mS AVRIP Tx: v=192.168.239.32 send=0 receive=1
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
489353mS PRN: AVRIP receive from pbx c0a8ef20 count = 1
495534mS AVRIP Tx: v=192.168.15.12 send=51 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
495534mS AVRIP Tx: v=192.168.239.32 send=1 receive=2
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
499208mS PRN: AVRIP receive from pbx c0a8ef20 count = 2
505535mS AVRIP Tx: v=192.168.15.12 send=52 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
505535mS AVRIP Tx: v=192.168.239.32 send=2 receive=3
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
509060mS PRN: AVRIP receive from pbx c0a8ef20 count = 3
515534mS AVRIP Tx: v=192.168.15.12 send=53 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
515534mS AVRIP Tx: v=192.168.239.32 send=3 receive=4
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
518910mS PRN: AVRIP receive from pbx c0a8ef20 count = 4
525534mS AVRIP Tx: v=192.168.15.12 send=54 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
525535mS AVRIP Tx: v=192.168.239.32 send=4 receive=5
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
528762mS PRN: AVRIP receive from pbx c0a8ef20 count = 5
535535mS AVRIP Tx: v=192.168.15.12 send=55 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
535535mS AVRIP Tx: v=192.168.239.32 send=0 receive=0
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
538610mS PRN: AVRIP receive from pbx c0a8ef20 count = 0
542721mS PRN: LCSE: 01a95804 (0 1012 0); H245 Received close channel: 65793
542721mS PRN: LCSE: 01a95804 (0 1012 0); H245 Sending close channel ack: 65793
542722mS PRN: LCSE: 01a95804 (0 1012 0); Deleting channel 65793
542729mS PRN: LCSE: 01a99494 (0 1012 0); H245 Sending close channel: 257
542730mS H323Evt: v=0 stacknum=50 State, new=ReleaseReq, old=Active id=1012
542731mS H323Evt: v=0 stacknum=50 State, new=NullState, old=ReleaseReq id=1012
542731mS PRN: LCSE: 01a99494 (0 1012 0); Deleting channel 257
542733mS CMLineRx: v=50
CMReleaseComp
Line: type=IPLine 50
Call: lid=0 id=1012 in=0
Product Unknown
Cause=16, Normal call clearing
542733mS PRN: CALL: 0.1011.0 Deleted leaving 0 CMCall objects
542733mS CMMap: a=17.30 b=250.9001 M0
542733mS PRN: Converted to:
542733mS CMMap: a=17.30 b=3.20 M0
542736mS CALL:2011/04/1408:29,00:07:39,001,679,O,313,313,DonkeyKong,,,0,,""n/a,0
542736mS CD: CALL: 0.1011.0 BState=Idle Cut=0 Music=0.0 Aend="DonkeyKong(679)" (17.30) Bend="Line 50" [Line 50] (250.9001) CalledNum=313 () CallingNum=679 () Internal=0 Time=459557 AState=Idle
542737mS CMExtnTx: v=679, p1=28
CMReleaseComp
Line: type=DigitalExtn 3
Call: lid=0 id=1011 in=0
Product Unknown
UUI type=Local [....] [0x03 0x01 0x00 0x0e ]
Timed: 14/04/11 08:37
542738mS CD: CALL: 0.1011.0 Deleted
542739mS CMExtnTx: v=, p1=1118
CMReleaseComp
Line: type=RAS 1
Call: lid=0 id=1118 in=0
BChan: slot=3 chan=20
Product Unknown
542918mS RES: Thu 14/4/2011 08:37:43 FreeMem=13146220(11) CMMsg=5 (6) Buff=200 850 1000 1319 5 Links=10883 Elements=154
545535mS AVRIP Tx: v=192.168.15.12 send=56 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
545535mS AVRIP Tx: v=192.168.239.32 send=0 receive=0
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
546788mS CMExtnRx: v=632, p1=24
CMSetup
Line: type=NoLine 0
Call: lid=0 id=-1 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
Product Unknown
546789mS CMTARGET: LOOKUP CALL ROUTE:21 type=100 called_party= sub= calling=632 in=0 complete=0
546789mS CMTARGET: ADD TARGET:21 number= type=100 depth=1 nobar=1 setorig=1 fwduser=
546789mS CMTARGET: LOOKUP CALL ROUTE:21 returned 0
546789mS CD: CALL: 0.1042.0 BState=Idle Cut=0 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="" [] (0.0) CalledNum= () CallingNum=632 () Internal=1 Time=1 AState=Dialling
546790mS CMExtnTx: v=632, p1=24
CMInbandTone
Line: type=DigitalExtn 3
Call: lid=0 id=-1 in=0
Product Unknown
UUI type=User2User [TD1\r\n] [0x54 0x44 0x31 0x0d 0x0a ]
Timed: 14/04/11 08:37
546790mS CMExtnTx: v=632, p1=24
CMSetupAck
Line: type=DigitalExtn 3
Call: lid=0 id=1042 in=0
BChan: slot=17 chan=26
Product Unknown
Timed: 14/04/11 08:37
546791mS CD: CALL: 0.1042.0 BState=Idle Cut=0 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="" [] (0.0) CalledNum= () CallingNum=632 () Internal=1 Time=3 AState=LocalDial
546791mS CMMap: a=17.26 b=0.0 D1
546793mS CMExtnRx: v=632, p1=24
CMInformation
Line: type=NoLine 0
Call: lid=0 id=-1 in=0
Called[3] Type=Default (100)
Product Unknown
546793mS CMExtnTx: v=632, p1=24
CMInbandTone
Line: type=DigitalExtn 3
Call: lid=0 id=-1 in=0
Product Unknown
UUI type=User2User [TD0\r\n] [0x54 0x44 0x30 0x0d 0x0a ]
Timed: 14/04/11 08:37
546794mS CMMap: a=17.26 b=0.0 D0
546795mS CMTARGET: LOOKUP CALL ROUTE:21 type=100 called_party=3 sub= calling=632 in=0 complete=0
546795mS CMTARGET: ADD TARGET:21 number=3 type=100 depth=1 nobar=1 setorig=1 fwduser=
546795mS CMTARGET: OVERLAP LOOKUP CALL ROUTE:21 returned 0
547335mS CMExtnRx: v=632, p1=24
CMInformation
Line: type=NoLine 0
Call: lid=0 id=-1 in=0
Called[2] Type=Default (100)
Product Unknown
547335mS CMTARGET: LOOKUP CALL ROUTE:21 type=100 called_party=32 sub= calling=632 in=0 complete=0
547336mS CMTARGET: ADD TARGET:21 number=32 type=100 depth=1 nobar=1 setorig=1 fwduser=
547336mS CMTARGET: OVERLAP LOOKUP CALL ROUTE:21 returned 0
548461mS PRN: AVRIP receive from pbx c0a8ef20 count = 0
549605mS CMExtnRx: v=632, p1=24
CMInformation
Line: type=NoLine 0
Call: lid=0 id=-1 in=0
Called[5] Type=Default (100)
Product Unknown
549605mS CMTARGET: LOOKUP CALL ROUTE:21 type=100 called_party=325 sub= calling=632 in=0 complete=0
549605mS CMTARGET: ADD TARGET:21 number=325 type=100 depth=1 nobar=1 setorig=1 fwduser=
549606mS CMTARGET: SYS SC:21 325 3 25 sc=type=Dial code=3XX, num=3N
549606mS CMTARGET: LCR NOT MATCHED:21 (cpn=325)
549606mS CMTARGET: DIAL LINE:21 GROUP=50 SUCCESS=1
549606mS CMTARGET: OVERLAP LOOKUP CALL ROUTE:21 returned 1
549607mS CD: CALL: 0.1042.0 BState=Idle Cut=1 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (0.0) CalledNum=325 () CallingNum=632 () Internal=0 Time=2818 AState=Dialling
549607mS CMExtnTx: v=632, p1=24
CMSetupAck
Line: type=DigitalExtn 3
Call: lid=0 id=1042 in=0
Called[325] Type=Default (100)
BChan: slot=17 chan=26
Product Unknown
Display [325]
Timed: 14/04/11 08:37
549608mS CD: CALL: 0.1042.0 BState=Idle Cut=1 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (0.0) CalledNum=325 () CallingNum=632 () Internal=0 Time=2820 AState=Dialled
549609mS CMLineTx: v=50
CMSetup
Line: type=IPLine 50
Call: lid=0 id=1043 in=0
Called[325] Type=Default (100) Calling[632] Type=Internal (101)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=250 chan=9003
Product Unknown
IE CMIETxChannelAudio (1) comptype=G729A8K (6) pktsize=20 ipaddr=192.168.239.32 port=0
Display [BugsySeagallla]
Cause=16, Normal call clearing
Locale: enu
549617mS H323Evt: v=0 stacknum=50 State, new=NullState, old=NullState id=-1
549619mS H323Evt: v=0 stacknum=50 State, new=Initiated, old=NullState id=1043
549689mS CMLineRx: v=50
CMFacility
Line: type=IPLine 50
Call: lid=0 id=1043 in=0
Product Unknown
UUI type=User2User [turn h323setuptimer off] [0x74 0x75 0x72 0x6e 0x20 0x68 0x33 0x32 0x33 0x73 0x65 0x74 0x75 0x70 0x74 0x69 0x6d 0x65 0x72 0x20 0x6f 0x66 0x66 ]
549918mS RES: Thu 14/4/2011 08:37:50 FreeMem=13132324(10) CMMsg=5 (6) Buff=200 850 1000 1319 5 Links=10874 Elements=154
549949mS H323Evt: v=0 stacknum=50 State, new=Proceeding, old=Initiated id=1043
549954mS CMLineRx: v=50
CMProceeding
Line: type=IPLine 50
Call: lid=0 id=1043 in=0
Product Standard
549955mS CD: CALL: 0.1042.0 BState=Dialled Cut=1 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (250.9003) CalledNum=325 () CallingNum=632 () Internal=0 Time=3167 AState=Dialled
549955mS CMExtnTx: v=632, p1=24
CMProceeding
Line: type=DigitalExtn 3
Call: lid=0 id=1042 in=0
Called[325] Type=Default (100)
BChan: slot=17 chan=26
Product Standard
Display [325]
Timed: 14/04/11 08:37
554476mS H323Evt: v=0 stacknum=50 State, new=Delivered, old=Proceeding id=1043
554481mS CMLineRx: v=50
CMAlerting
Line: type=IPLine 50
Call: lid=0 id=1043 in=0
Product Standard
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
554481mS CD: CALL: 0.1042.0 BState=Ringing Cut=1 Music=0.0 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (250.9003) CalledNum=325 () CallingNum=632 () Internal=0 Time=7693 AState=Dialled
554481mS CMMap: a=17.26 b=0.0 R1
554483mS CD: CALL: 0.1042.0 BState=Ringing Cut=1 Music=2.0 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (250.9003) CalledNum=325 () CallingNum=632 () Internal=0 Time=7695 AState=Ringing
554483mS CMExtnTx: v=632, p1=24
CMAlerting
Line: type=DigitalExtn 3
Call: lid=0 id=1042 in=0
Called[325] Type=Default (100)
BChan: slot=17 chan=26
Product Standard
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
Display [325]
Timed: 14/04/11 08:37
555534mS AVRIP Tx: v=192.168.15.12 send=57 receive=0
requiresvoicemail 0 operational 1 version 2 recordsupported 1
IPaddr 255.255.255.255 hops=0 voice=0
IPaddr 192.168.239.32 hops=1 voice=0
555534mS AVRIP Tx: v=192.168.239.32 send=0 receive=0
PLATFORM: requiresvoicemail 0 operational 1 version 2 recordsupported 1
557999mS CMExtnRx: v=632, p1=24
CMReleaseComp
Line: type=NoLine 0
Call: lid=0 id=1042 in=0
Product Unknown
557999mS PRN: CALL: 0.1042.0 Deleted leaving 0 CMCall objects
558000mS CMMap: a=17.26 b=0.0 R0
558001mS CALL:2011/04/1408:37,00:00:00,003,632,O,325,325,HamiltonUrquil,,,0,,""n/a,0
558001mS CD: CALL: 0.1042.0 BState=Idle Cut=0 Music=0.1 Aend="BugsySeagall(632)" (17.26) Bend="Line 50" [Line 50] (250.9003) CalledNum=325 () CallingNum=632 () Internal=0 Time=11213 AState=Idle
558002mS CMLineTx: v=50
CMReleaseComp
Line: type=IPLine 50
Call: lid=0 id=1043 in=0
Product Unknown
Cause=16, Normal call clearing
558003mS CD: CALL: 0.1042.0 Deleted
558003mS CMExtnTx: v=632, p1=24
CMReleaseComp
Line: type=DigitalExtn 3
Call: lid=0 id=1042 in=0
Product Unknown
UUI type=Local [....] [0x03 0x06 0x03 0x0e ]
Timed: 14/04/11 08:37
558008mS H323Evt: v=0 stacknum=50 State, new=ReleaseReq, old=Delivered id=1043
558009mS H323Evt: v=0 stacknum=50 State, new=NullState, old=ReleaseReq id=1043
558314mS PRN: AVRIP receive from pbx c0a8ef20 count = 0
558417mS RES: Thu 14/4/2011 08:37:59 FreeMem=13144516(11) CMMsg=5 (6) Buff=200 849 1000 1319 5 Links=10881 Elements=154

********** Warning: Logging to Screen Stopped **********



Beets? Yes! Ask me how I feel about Beets! I like Beets!

 

[amriddle01]

Hmmmm, why not export all users on both sites, delete them from the system(s) and import back, will take but a few moments to do and if your other testing holds out they should all work again.

OK....perhaps it is a corrupted configuration then?

ACSS (SME)
APSS (SME)

http://www.ipoffice.tel

"I'm just off to Hartlepool to buy some exploding trousers

 

[Rappy]

i have never done an export. Ooh!

It may just be corrupted, hence the reason i am dilligent about backing up a config at least once a week, or when changes are made.

I will look into it and see what i need to do. Thanks for the suggestion!

Beets? Yes! Ask me how I feel about Beets! I like Beets!

 

[mattKnight]

Run a wireshark, and filter it for traffic on the AVRIP port.

It'll probably be relatively quick to tell which exten is blocking the transmission

Take Care

Matt
I have always wished that my computer would be as easy to use as my telephone.
My wish has come true. I no longer know how to use my telephone.

 

[Rappy]

i have been unable to run the wireshark traces as that laptop went kaput last time i fired up wireshark. However, i do remember seeing AVRIP traffic in monitor.

i believe i may have actually found the issue, and may have a fix for it, but i still need to test my theory.

In the IPO 406 AND 412 i need to add explicit routes to the subnets where the IPOs are located- not just add one route and expect packets to be forwarded to the adjacent VLAN via routing protocols or layer 3 switching, but rather, the IPO NEEDS routes to the IPO On the far end (406 to the 412 needs 192.168.2.0 via 192.168.2.1; 192.168.1.0 via 192.168.239.1 and 192.168.2.1, and the 412 needs routes in reverse). While i run MPLS and my network has propagated via the routing protocol to both near and far end devices, the IPO needed help in getting to routes via the layer 3 switch and the router. i did this by adding routes to EVERYWHERE! i have done the same on the 412 side, and this has already helped me to fix an issue i was having with the phone manager application i had not mentioned: i could not get the application to work for the users on the 406. once i added these routes, they were able to connect successfully.

The weird part is that i was able to connect to the ipo 406 via the phone manager application from the 412's subnet (IPO 412- 192.168.1.0; IPO 406- 192.168.239.0) without any issue ALL ALONG without adding the routes i mentioned above. it is too late on a friday for me to go testing rebooting the IPO at this time to see if we get our caller ID back on the 412 side right now, so i will report back as soon as i have something. Thanks for your help!

Beets? Yes! Ask me how I feel about Beets! I like Beets!

 

[amriddle01]

Sounds like a networking issue afterall one 0.0.0.0 route would work if all other subnets are via the same gateway

ACSS (SME)
APSS (SME)

http://www.ipoffice.tel

"I'm just off to Hartlepool to buy some exploding trousers