Thanks again Paolo, I have made some progress, as oddly I noticed in the ASA that the show sip command returned nothing. A reboot of the ASA and I do now see information in response to the show sip command.... So we now know that the inspect sip is doing something. I may be being mislead by the data, but the UC540 still isn't showing as being registered on the SIP trunk... BUT I have found that I can make incoming calls to it. Voice works both ways and is fine. Now I find that an outgoing call can't be made. It strikes me as odd that incoming calls can be received if the trunk is not registered... but maybe that is how it functions? Now I am not sure if it is a SIP NAT issue at the ASA, or if I am back to looking at the UC540. If you think it is ASA related I shall repost my question in the security/firewall section as you suggested. Thanks Mark ... View more

Hi again Paolo, I do have    policy-map global_policy         class inspection_default           inspect sip in the ASA, so I think we should be correctly setup. There  may be a hole in my knowledge here, I always thought that the  fixup/inspect statements applied to the IP addresses inside user data within the packet, (the  static NAT doing the IP header stuff)... I wasn't aware that the inspect  should be manipulating the TEXT contents of the packet... or have I  misunderstood your reply? ... View more

Hi Paulo, I am not sure I am fully understaning SIP traffic at the moment... I think that the IP level traffic is fine... what I think is that the CONTENTS of the messages is wrong not the IP headers. As far as I understand it, SIP is text based, what I think the issue is here is that the service provider is looking for some specific text in the message. We send eg. "INVITE sip:0123456789@192.180.10.128 SIP/2.0" whereas the provider is expecting "INVITE sip:0123456789@46.47.48.49 SIP/2.0" (where in the example 192... is our private address, and 46.47.48.49 is an  example of an outside address. They tell me I can also use the form "INVITE sip:0123456789@your.domain.local SIP/2.0" I am lead to believe there is a way of setting the text in the SIP traffic? I realise I may be barking up the wrong tree on this though. ... View more

Hi Paulo, Thanks for the reply. I probably should have given a bit more of a description of the setup... it is my 'standard' setup of an UC540 behind and ASA 5505 doing static NAT, and front ended by an 887VA giving me a connection to FTTC (VDSL). I have this running with no issues using another SIP provider... it just looks like the issue may be the way Gamma Telecom do their SIP. The examples I have from them show for example the INVITE message as having sip:OurPSTNNumber@OurPublicIP SIP/2.0 I looked at another UC540 which is up and running (different provider, user/password authentication) and that seems to work fine even though I see the SIP messages as being in the format sip:UserName@OurPrivateIP SIP/2.0 I don't think the issue is t the IP level, more the SIP level (though I may be wrong). ... View more

Just to update everyone on this... I visited site today and despite my contact being certain their old system used to show incomming numbers, we find that the service is not enabled by BT on that BRI channel. We have asked for the caller id to be added to that BRI channel which should be completed sometime this week. Hopefully that will resolve the issue. Sorry to have wasted everyone's time... I shouldn't have listened to the customer... I should have done my own tests. ... View more

Hi Alex, Yes, I can confirm the switch type is basic-net3 Slightly odd thing is that the following line appears at the config level isdn switch-type basic-net3 Then appears again under the interface level. Not relevant to my issue, but don't know why it appears at the top level too. interface BRI0/1/0 no ip address isdn switch-type basic-net3 isdn point-to-point-setup isdn incoming-voice voice isdn sending-complete isdn static-tei 0 trunk-group ALL_BRI 64 ... View more

Hi John, I ran the debug and get the following; No where do I see the calling number appear.... So it looks to me like the number isn't being supplied by the service provider (BT). Will check again with the customer tomorrow... their representative today stated that the old system (that we are replacing) used to show the calling number. They may of course be wrong! I really should have tested this before decommisioning the old unit. Syslog logging: enabled (0 messages dropped, 9 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator.     Console logging: disabled     Monitor logging: disabled     Buffer logging:  level debugging, 402 messages logged, xml disabled,                     filtering disabled     Exception Logging: size (4096 bytes)     Count and timestamp logging messages: disabled     Persistent logging: disabled No active filter modules.     Trap logging: level informational, 353 message lines logged         Logging Source-Interface:       VRF Name: Log Buffer (5000000 bytes): 002681: Jan 28 20:15:04.499: %SYS-5-CONFIG_I: Configured from http by root on 192.168.10.200 002682: Jan 28 20:15:04.623: %SYS-5-CONFIG_I: Configured from http by root on 192.168.10.200 002683: Jan 28 20:15:12.631: //-1/417C43919BFD/CCAPI/cc_api_display_ie_subfields:    cc_api_call_setup_ind_common:    cisco-username=    ----- ccCallInfo IE subfields -----    cisco-ani=BCD    cisco-anitype=0    cisco-aniplan=0    cisco-anipi=0    cisco-anisi=0    dest=    cisco-desttype=0    cisco-destplan=0    cisco-rdie=FFFFFFFF    cisco-rdn=    cisco-rdntype=0    cisco-rdnplan=0    cisco-rdnpi=0    cisco-rdnsi=0    cisco-redirectreason=0   fwd_final_type =0    final_redirectNumber =    hunt_group_timeout =0 002684: Jan 28 20:15:12.631: //-1/417C43919BFD/CCAPI/cc_api_call_setup_ind_common:    Interface=0x88EB7844, Call Info(    Calling Number=BCD,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=(TON=Unknown, NPI=Unknown),    Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,    Incoming Dial-peer=20001, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,    Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 002685: Jan 28 20:15:12.631: //-1/417C43919BFD/CCAPI/ccCheckClipClir:    In: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002686: Jan 28 20:15:12.631: //-1/417C43919BFD/CCAPI/ccCheckClipClir:    Out: Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002687: Jan 28 20:15:12.631: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002688: Jan 28 20:15:12.631: :cc_get_feature_vsa malloc success 002689: Jan 28 20:15:12.631: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002690: Jan 28 20:15:12.631:  cc_get_feature_vsa count is 1 002691: Jan 28 20:15:12.631: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002692: Jan 28 20:15:12.631: :FEATURE_VSA attributes are: feature_name:0,feature_time:2298273336,feature_id:10052 002693: Jan 28 20:15:12.631: //10040/417C43919BFD/CCAPI/cc_api_call_setup_ind_common:    Set Up Event Sent;    Call Info(Calling Number=BCD(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=(TON=Unknown, NPI=Unknown)) 002694: Jan 28 20:15:12.631: //10040/417C43919BFD/CCAPI/cc_process_call_setup_ind:    Event=0x885DD368 002695: Jan 28 20:15:12.631: //10040/417C43919BFD/CCAPI/ccCallSetContext:    Context=0x89850758 002696: Jan 28 20:15:12.631: //10040/417C43919BFD/CCAPI/cc_process_call_setup_ind:    >>>>CCAPI handed cid 10040 with tag 20001 to app "_ManagedAppProcess_Default" 002697: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccCallSetupAck:    Call Id=10040 002698: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_set_transfer_info:    Transfer Number=, Transfer Reason=0x0 002699: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccGenerateToneInfo:    Stop Tone On Digit=TRUE, Tone=Dial Tone,    Tone Direction=Network, Params=0x0, Call Id=10040 002700: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccSetDigitTimeouts:    Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) 002701: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccSetDigitTimeouts:    Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) 002702: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccCallReportDigits:    (callID=0x2738, digit_event=0x1, enable=TRUE, consume=FALSE) 002703: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/ccCallReportDigits:    Enabled=TRUE, Call Id=10040 002704: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    (vdbPtr=0x88EB7844, callID=0x2738, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) 002705: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    Enabled=TRUE, Disposition=0x0, Interface=0x88EB7844, Call Id=10040 002706: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms)) 002707: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_digit_begin:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=A, DigitBeginFlags=0x0,    Rtp Timestamp=0x39490DE8, Rtp Expiration=0x0 002708: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=A, Duration=100,    Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF 002709: Jan 28 20:15:12.635: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Call Entry(Handoff Depth=0) 002710: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_begin:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=B, DigitBeginFlags=0x0,    Rtp Timestamp=0x39498AE8, Rtp Expiration=0x0 002711: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=B, Duration=100,    Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF 002712: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Call Entry(Handoff Depth=0) 002713: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_begin:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=C, DigitBeginFlags=0x0,    Rtp Timestamp=0x394A07E8, Rtp Expiration=0x0 002714: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,    Source Call Id=10040, Digit=C, Duration=100,    Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF 002715: Jan 28 20:15:12.639: //10040/417C43919BFD/CCAPI/cc_api_call_digit_end:    Call Entry(Handoff Depth=0) 002716: Jan 28 20:15:12.659: //10040/417C43919BFD/CCAPI/ccCallReportDigits:    (callID=0x2738, digit_event=0x0, enable=FALSE, consume=FALSE) 002717: Jan 28 20:15:12.663: //10040/417C43919BFD/CCAPI/ccCallReportDigits:    Enabled=TRUE, Call Id=10040 002718: Jan 28 20:15:12.663: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    (vdbPtr=0x88EB7844, callID=0x2738, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) 002719: Jan 28 20:15:12.663: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    Enabled=TRUE, Disposition=0x0, Interface=0x88EB7844, Call Id=10040 002720: Jan 28 20:15:12.663: //10040/417C43919BFD/CCAPI/cc_api_call_report_digits_done:    Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms)) 002721: Jan 28 20:15:12.663: //10040/417C43919BFD/CCAPI/ccCallProceeding:    Progress Indication=NULL(0) 002722: Jan 28 20:15:12.667: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:    Try with the demoted called number ABC 002723: Jan 28 20:15:12.667: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa: 002724: Jan 28 20:15:12.667: : updating existing feature vsa 002725: Jan 28 20:15:12.667: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa: 002726: Jan 28 20:15:12.667:  feature call basic 002727: Jan 28 20:15:12.667: //10040/417C43919BFD/CCAPI/ccCallDisconnect:    Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 002728: Jan 28 20:15:12.667: //10040/417C43919BFD/CCAPI/ccCallDisconnect:    Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17) 002729: Jan 28 20:15:12.667: //10040/417C43919BFD/CCAPI/cc_api_get_transfer_info:    Transfer Number Is Null 002730: Jan 28 20:15:12.679: //10040/417C43919BFD/CCAPI/cc_api_call_disconnect_done:    Disposition=0, Interface=0x88EB7844, Tag=0x0, Call Id=10040,    Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0) 002731: Jan 28 20:15:12.679: //10040/417C43919BFD/CCAPI/cc_api_call_disconnect_done:    Call Disconnect Event Sent 002732: Jan 28 20:15:12.679: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002733: Jan 28 20:15:12.679: :cc_free_feature_vsa freeing 88FCDE30 002734: Jan 28 20:15:12.679: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002735: Jan 28 20:15:12.679:  vsacount in free is 0 002736: Jan 28 20:15:13.975: ISDN BR0/1/1 Q931: RX <- SETUP pd = 8  callref = 0x01     Sending Complete     Bearer Capability i = 0x9090A3         Standard = CCITT         Transfer Capability = 3.1kHz Audio         Transfer Mode = Circuit         Transfer Rate = 64 kbit/s     Channel ID i = 0x89         Exclusive, B1     Called Party Number i = 0x81, '883714'         Plan:ISDN, Type:Unknown 002737: Jan 28 20:15:13.983: //-1/424A90BE8017/CCAPI/cc_api_display_ie_subfields:    cc_api_call_setup_ind_common:    cisco-username=    ----- ccCallInfo IE subfields -----    cisco-ani=    cisco-anitype=0    cisco-aniplan=0    cisco-anipi=0    cisco-anisi=0    dest=883714    cisco-desttype=0    cisco-destplan=1    cisco-rdie=FFFFFFFF    cisco-rdn=    cisco-rdntype=-1    cisco-rdnplan=-1    cisco-rdnpi=-1    cisco-rdnsi=-1    cisco-redirectreason=-1   fwd_final_type =0    final_redirectNumber =    hunt_group_timeout =0 002738: Jan 28 20:15:13.983: //-1/424A90BE8017/CCAPI/cc_api_call_setup_ind_common:    Interface=0x8865A53C, Call Info(    Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=883714(TON=Unknown, NPI=ISDN),    Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,    Incoming Dial-peer=3007, Progress Indication=NULL(0), Calling IE Present=FALSE,    Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1 002739: Jan 28 20:15:13.983: //-1/424A90BE8017/CCAPI/ccCheckClipClir:    In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002740: Jan 28 20:15:13.983: //-1/424A90BE8017/CCAPI/ccCheckClipClir:    Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002741: Jan 28 20:15:13.983: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002742: Jan 28 20:15:13.983: :cc_get_feature_vsa malloc success 002743: Jan 28 20:15:13.983: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002744: Jan 28 20:15:13.983:  cc_get_feature_vsa count is 1 002745: Jan 28 20:15:13.983: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002746: Jan 28 20:15:13.983: :FEATURE_VSA attributes are: feature_name:0,feature_time:2298273336,feature_id:10053 002747: Jan 28 20:15:13.983: //10041/424A90BE8017/CCAPI/cc_api_call_setup_ind_common:    Set Up Event Sent;    Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=883714(TON=Unknown, NPI=ISDN)) 002748: Jan 28 20:15:13.983: //10041/424A90BE8017/CCAPI/cc_process_call_setup_ind:    Event=0x885E0A40 002749: Jan 28 20:15:13.983: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:    Try with the demoted called number 883714 002750: Jan 28 20:15:13.983: //10041/424A90BE8017/CCAPI/ccCallSetContext:    Context=0x89846158 002751: Jan 28 20:15:13.987: //10041/424A90BE8017/CCAPI/cc_process_call_setup_ind:    >>>>CCAPI handed cid 10041 with tag 3007 to app "_ManagedAppProcess_Default" 002752: Jan 28 20:15:13.987: //10041/424A90BE8017/CCAPI/ccCallProceeding:    Progress Indication=NULL(0) 002753: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination=, Calling IE Present=FALSE, Mode=0,    Outgoing Dial-peer=20009, Params=0x8984C968, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) 002754: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002755: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002756: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination Pattern=203$, Called Number=203, Digit Strip=TRUE 002757: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=203(TON=Unknown, NPI=ISDN),    Redirect Number=, Display Info=    Account Number=, Final Destination Flag=TRUE,    Guid=424A90BE-68BE-11E2-8017-A4934C6F1FC0, Outgoing Dial-peer=20009 002758: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/cc_api_display_ie_subfields:    ccCallSetupRequest:    cisco-username=    ----- ccCallInfo IE subfields -----    cisco-ani=    cisco-anitype=0    cisco-aniplan=0    cisco-anipi=0    cisco-anisi=0    dest=203    cisco-desttype=0    cisco-destplan=1    cisco-rdie=FFFFFFFF    cisco-rdn=    cisco-rdntype=-1    cisco-rdnplan=-1    cisco-rdnpi=-1    cisco-rdnsi=-1    cisco-redirectreason=-1   fwd_final_type =0    final_redirectNumber =    hunt_group_timeout =0 002759: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    Interface=0x896F720C, Interface Type=6, Destination=, Mode=0x0,    Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=203(TON=Unknown, NPI=ISDN), Calling Translated=FALSE,    Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20009, Call Count On=FALSE,    Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=) 002760: Jan 28 20:15:13.991: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002761: Jan 28 20:15:13.991: :cc_get_feature_vsa malloc success 002762: Jan 28 20:15:13.991: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002763: Jan 28 20:15:13.991:  cc_get_feature_vsa count is 2 002764: Jan 28 20:15:13.991: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002765: Jan 28 20:15:13.991: :FEATURE_VSA attributes are: feature_name:0,feature_time:2298271992,feature_id:10054 002766: Jan 28 20:15:13.991: //10042/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 002767: Jan 28 20:15:13.991: //10042/424A90BE8017/CCAPI/ccCallSetContext:    Context=0x8984C918 002768: Jan 28 20:15:13.991: //10041/424A90BE8017/CCAPI/ccSaveDialpeerTag:    Outgoing Dial-peer=20009 002769: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination=, Calling IE Present=FALSE, Mode=0,    Outgoing Dial-peer=20008, Params=0x89853128, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) 002770: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002771: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002772: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination Pattern=204$, Called Number=204, Digit Strip=TRUE 002773: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=204(TON=Unknown, NPI=ISDN),    Redirect Number=, Display Info=    Account Number=, Final Destination Flag=TRUE,    Guid=424C6536-68BE-11E2-9C06-B4DB825DC8FC, Outgoing Dial-peer=20008 002774: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/cc_api_display_ie_subfields:    ccCallSetupRequest:    cisco-username=    ----- ccCallInfo IE subfields -----    cisco-ani=    cisco-anitype=0    cisco-aniplan=0    cisco-anipi=0    cisco-anisi=0    dest=204    cisco-desttype=0    cisco-destplan=1    cisco-rdie=FFFFFFFF    cisco-rdn=    cisco-rdntype=-1    cisco-rdnplan=-1    cisco-rdnpi=-1    cisco-rdnsi=-1    cisco-redirectreason=-1   fwd_final_type =0    final_redirectNumber =    hunt_group_timeout =0 002775: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    Interface=0x895B876C, Interface Type=6, Destination=, Mode=0x0,    Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=204(TON=Unknown, NPI=ISDN), Calling Translated=FALSE,    Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20008, Call Count On=FALSE,    Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=) 002776: Jan 28 20:15:13.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002777: Jan 28 20:15:13.995: :cc_get_feature_vsa malloc success 002778: Jan 28 20:15:13.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002779: Jan 28 20:15:13.995:  cc_get_feature_vsa count is 3 002780: Jan 28 20:15:13.995: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002781: Jan 28 20:15:13.995: :FEATURE_VSA attributes are: feature_name:0,feature_time:2298273560,feature_id:10055 002782: Jan 28 20:15:13.995: //10043/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 002783: Jan 28 20:15:13.995: //10043/424A90BE8017/CCAPI/ccCallSetContext:    Context=0x898530D8 002784: Jan 28 20:15:13.995: //10041/424A90BE8017/CCAPI/ccSaveDialpeerTag:    Outgoing Dial-peer=20008 002785: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination=, Calling IE Present=FALSE, Mode=0,    Outgoing Dial-peer=20007, Params=0x89840EA8, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) 002786: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002787: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccCheckClipClir:    Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) 002788: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Destination Pattern=205$, Called Number=205, Digit Strip=TRUE 002789: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccCallSetupRequest:    Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=205(TON=Unknown, NPI=ISDN),    Redirect Number=, Display Info=    Account Number=, Final Destination Flag=TRUE,    Guid=424D01F6-68BE-11E2-9C09-B4DB825DC8FC, Outgoing Dial-peer=20007 002790: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/cc_api_display_ie_subfields:    ccCallSetupRequest:    cisco-username=    ----- ccCallInfo IE subfields -----    cisco-ani=    cisco-anitype=0    cisco-aniplan=0    cisco-anipi=0    cisco-anisi=0    dest=205    cisco-desttype=0    cisco-destplan=1    cisco-rdie=FFFFFFFF    cisco-rdn=    cisco-rdntype=-1    cisco-rdnplan=-1    cisco-rdnpi=-1    cisco-rdnsi=-1    cisco-redirectreason=-1   fwd_final_type =0    final_redirectNumber =    hunt_group_timeout =0 002791: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    Interface=0x898B912C, Interface Type=6, Destination=, Mode=0x0,    Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),    Called Number=205(TON=Unknown, NPI=ISDN), Calling Translated=FALSE,    Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20007, Call Count On=FALSE,    Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=) 002792: Jan 28 20:15:13.999: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002793: Jan 28 20:15:13.999: :cc_get_feature_vsa malloc success 002794: Jan 28 20:15:13.999: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002795: Jan 28 20:15:13.999:  cc_get_feature_vsa count is 4 002796: Jan 28 20:15:13.999: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: 002797: Jan 28 20:15:13.999: :FEATURE_VSA attributes are: feature_name:0,feature_time:2298273112,feature_id:10056 002798: Jan 28 20:15:13.999: //10044/424A90BE8017/CCAPI/ccIFCallSetupRequestPrivate:    SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 002799: Jan 28 20:15:13.999: //10044/424A90BE8017/CCAPI/ccCallSetContext:    Context=0x89840E58 002800: Jan 28 20:15:13.999: //10041/424A90BE8017/CCAPI/ccSaveDialpeerTag:    Outgoing Dial-peer=20007 002801: Jan 28 20:15:14.003: //10042/424A90BE8017/CCAPI/cc_api_update_call_info:    Interface=0x896F720C, Call Id=0x273A 002802: Jan 28 20:15:14.003: //10043/424A90BE8017/CCAPI/cc_api_update_call_info:    Interface=0x895B876C, Call Id=0x273B 002803: Jan 28 20:15:14.007: //10044/424A90BE8017/CCAPI/cc_api_update_call_info:    Interface=0x898B912C, Call Id=0x273C 002804: Jan 28 20:15:14.007: //10042/424A90BE8017/CCAPI/cc_api_call_proceeding:    Interface=0x896F720C, Progress Indication=NULL(0) 002805: Jan 28 20:15:14.011: //10043/424A90BE8017/CCAPI/cc_api_call_proceeding:    Interface=0x895B876C, Progress Indication=NULL(0) 002806: Jan 28 20:15:14.011: //10044/424A90BE8017/CCAPI/cc_api_call_proceeding:    Interface=0x898B912C, Progress Indication=NULL(0) 002807: Jan 28 20:15:14.019: //10042/424A90BE8017/CCAPI/cc_api_call_alert:    Interface=0x896F720C, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 002808: Jan 28 20:15:14.019: //10042/424A90BE8017/CCAPI/cc_api_call_alert:    Call Entry(Retry Count=0, Responsed=TRUE) 002809: Jan 28 20:15:14.019: //10043/424A90BE8017/CCAPI/cc_api_call_alert:    Interface=0x895B876C, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 002810: Jan 28 20:15:14.019: //10043/424A90BE8017/CCAPI/cc_api_call_alert:    Call Entry(Retry Count=0, Responsed=TRUE) 002811: Jan 28 20:15:14.019: //10044/424A90BE8017/CCAPI/cc_api_call_alert:    Interface=0x898B912C, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 002812: Jan 28 20:15:14.019: //10044/424A90BE8017/CCAPI/cc_api_call_alert:    Call Entry(Retry Count=0, Responsed=TRUE) 002813: Jan 28 20:15:14.031: ISDN BR0/1/1 Q931: TX -> CALL_PROC pd = 8  callref = 0x81     Channel ID i = 0x89         Exclusive, B1 002814: Jan 28 20:15:14.035: //10041/424A90BE8017/CCAPI/ccCallAlert:    Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1) 002815: Jan 28 20:15:14.035: //10041/424A90BE8017/CCAPI/ccCallAlert:    Call Entry(Responsed=TRUE, Alert Sent=TRUE) 002816: Jan 28 20:15:14.035: //10041/424A90BE8017/CCAPI/ccGenerateToneInfo:    Stop Tone On Digit=FALSE, Tone=Ring Back,    Tone Direction=Network, Params=0x0, Call Id=10041 002817: Jan 28 20:15:14.035: //10042/424A90BE8017/CCAPI/ccCallFeature:    Feature Type=25, Call Id=10042 002818: Jan 28 20:15:14.079: ISDN BR0/1/1 Q931: TX -> ALERTING pd = 8  callref = 0x81     Progress Ind i = 0x8188 - In-band info or appropriate now available 002819: Jan 28 20:15:28.075: ISDN BR0/1/1 Q931: RX <- DISCONNECT pd = 8  callref = 0x01     Cause i = 0x8090 - Normal call clearing 002820: Jan 28 20:15:28.079: ISDN BR0/1/1 Q931: TX -> RELEASE pd = 8  callref = 0x81     Cause i = 0x8090 - Normal call clearing 002821: Jan 28 20:15:28.079: //10041/424A90BE8017/CCAPI/cc_api_call_disconnected:    Cause Value=16, Interface=0x8865A53C, Call Id=10041 002822: Jan 28 20:15:28.079: //10041/424A90BE8017/CCAPI/cc_api_call_disconnected:    Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0) 002823: Jan 28 20:15:28.083: //10041/424A90BE8017/CCAPI/ccGenerateToneInfo:    Stop Tone On Digit=FALSE, Tone=Null,    Tone Direction=Network, Params=0x0, Call Id=10041 002824: Jan 28 20:15:28.083: //10042/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 002825: Jan 28 20:15:28.083: //10042/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 002826: Jan 28 20:15:28.083: //10042/424A90BE8017/CCAPI/cc_api_get_transfer_info:    Transfer Number Is Null 002827: Jan 28 20:15:28.083: //10044/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 002828: Jan 28 20:15:28.083: //10044/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0) 002829: Jan 28 20:15:28.083: //10044/424A90BE8017/CCAPI/cc_api_get_transfer_info:    Transfer Number Is Null 002830: Jan 28 20:15:28.083: //10043/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=0, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) 002831: Jan 28 20:15:28.083: //10043/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=0, Call Entry(Responsed=TRUE, Cause Value=0) 002832: Jan 28 20:15:28.083: //10043/424A90BE8017/CCAPI/cc_api_get_transfer_info:    Transfer Number Is Null 002833: Jan 28 20:15:28.087: //10041/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16) 002834: Jan 28 20:15:28.087: //10041/424A90BE8017/CCAPI/ccCallDisconnect:    Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16) 002835: Jan 28 20:15:28.087: //10041/424A90BE8017/CCAPI/cc_api_get_transfer_info:    Transfer Number Is Null 002836: Jan 28 20:15:28.103: //10042/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Disposition=0, Interface=0x896F720C, Tag=0x0, Call Id=10042,    Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 002837: Jan 28 20:15:28.103: //10042/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Call Disconnect Event Sent 002838: Jan 28 20:15:28.103: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002839: Jan 28 20:15:28.103: :cc_free_feature_vsa freeing 88FCD8F0 002840: Jan 28 20:15:28.103: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002841: Jan 28 20:15:28.103:  vsacount in free is 3 002842: Jan 28 20:15:28.103: //10044/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Disposition=0, Interface=0x898B912C, Tag=0x0, Call Id=10044,    Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0) 002843: Jan 28 20:15:28.107: //10044/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Call Disconnect Event Sent 002844: Jan 28 20:15:28.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002845: Jan 28 20:15:28.107: :cc_free_feature_vsa freeing 88FCDD50 002846: Jan 28 20:15:28.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002847: Jan 28 20:15:28.107:  vsacount in free is 2 002848: Jan 28 20:15:28.107: //10043/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Disposition=0, Interface=0x895B876C, Tag=0x0, Call Id=10043,    Call Entry(Disconnect Cause=0, Voice Class Cause Code=0, Retry Count=0) 002849: Jan 28 20:15:28.107: //10043/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Call Disconnect Event Sent 002850: Jan 28 20:15:28.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002851: Jan 28 20:15:28.107: :cc_free_feature_vsa freeing 88FCDF10 002852: Jan 28 20:15:28.107: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002853: Jan 28 20:15:28.107:  vsacount in free is 1 002854: Jan 28 20:15:28.187: ISDN BR0/1/1 Q931: RX <- RELEASE_COMP pd = 8  callref = 0x01 002855: Jan 28 20:15:28.191: //10041/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Disposition=0, Interface=0x8865A53C, Tag=0x0, Call Id=10041,    Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0) 002856: Jan 28 20:15:28.191: //10041/424A90BE8017/CCAPI/cc_api_call_disconnect_done:    Call Disconnect Event Sent 002857: Jan 28 20:15:28.191: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002858: Jan 28 20:15:28.191: :cc_free_feature_vsa freeing 88FCDE30 002859: Jan 28 20:15:28.191: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: 002860: Jan 28 20:15:28.191:  vsacount in free is 0 ... View more

An update for everyone on this thread: We didn't really manage to get the setup 100% stable. The new firmware and OS seeemd to help, but still had some times when it would drop off the network. Paolo is, I think, on the right track. Clearly whatever the issue, the router should detect the condition and re-establish... and it is not doing so. So, in my mind there is at least a firmware issue, in that the router doesn't properly handle the disconnect issue. It is a seperate question as to why the drop occurs in the first place. We managed to get BT to physically test the line at the premises and found that there were issues that remote testing did not pickup. (As it happens we knew the engineer they sent out, and he made extra efforts to make sure things were 100% even though it may previously have counted as acceptable). Corrosion was found in the street cabinet and a junction at the top of the pole. Both issues being corrected gave us a more stable connection (to be fair, this was the same time as firmare was changed, so I can't be 100% which item made the difference). As it happens we have now moved this connection to FTTC. Same line, same 887VA and have this working too. No drops detected so far with this configuration. I do seem to have a possible issue with MTU.... but maybe that is for another thread. Thanks for everyones thoughts and help on this one. ... View more

Hi Rod, Just as an update to this... my original problem was with the 887VA which is of course ADSL over POTS. I han't spotted yours was an 886VA which I believe is ADSL over ISDN. I didn't have any experience with that one and am unsure if the firmware I spoke about was correct for that configuration. Maybe that is why there was no carrier detected. I had a quick look at the original Cisco/BT document and didn't see discussions for the ISDN option. I hope I didn't mislead you. ... View more

Hi Rod, Just sent you a PM with the config. I am mostly happy that it is right, but I don't want to be posting up the config as a 'solution' yet as it may mislead others. ... View more