Solved: UC 540 call forward to external destination

unpersonal · ‎06-27-2013

hello everybody

i've a urgent problem with a uc 540. i hope you can help me...

i'd like to forward calls from an internal shared extension (as example 25) to an external destination (as example my home phone).

if i call the (shared) extension (that is forwarded to my home phone) from another internal extension, the redirection works fine.

if i call the (shared) extension (that is forwarded to my home phone) from external (as example from my mobile (handy) phone), the redirection fails. i hear a busy tone (fast beeps like i'm busy).

if i test the same with a normal extension: equal result.

i tested the same with different forward methods (directly on the phone, on the uc 540 (extension), other extension types): equal result

it seems to be a problem with forward rights from external, but i can't find any options to configure such rights...

all other works fine.

we use bri and i configure the uc 540 with cisco configuration assistant.

the customer likes to redirect all calls after 20 sec to his external mobile phone.

does anyone have any idea? i searched already x hours without success and we should have a solution... :-(

definition of external (because of my ugly english): i mean a phone, which is located outside the uc 540 network (pstn)

this did not help:

http://sbkb.cisco.com/CiscoSB/GetArticle.aspx?docid=225105528e1c46ad94c8cdc559804aa7_Enable_Call_Forwarding_to_Outside_Extension_on_UC500_series.xml

the prefix for the outgoing line is set.

thank you very much!

best regards

claude

paolo bevilacqua · ‎07-30-2013

try

interface bri01/0

isdn map address . plan unknown type unknown

View solution in original post

Alexander Maroukian · ‎06-28-2013

Hello Claude,

You may try using SNR(Single Number Reach) this feature can call the external number after few seconds as it is set if the extension is not answered.

HTH,

Alex

*Please rate helpful posts

unpersonal · ‎07-01-2013

Hey

thank you for reply!

i tried this already without success... unfortunately it behaves the same.

any other ideas? i really think that external forwarding is denied on the uc.

regards

claude

paolo bevilacqua · ‎07-01-2013

Take "debug isdn q931" with "term mon" for a failed, and for a succesful call.

unpersonal · ‎07-02-2013

sorry, i don't know what to do exactly. should i do this with ssh (terminal)? can you please describe the steps?

Brian Rapier · ‎07-12-2013

We ran into something similar with our setup.

What at the digits being dialed when you are trying to reach the outside number? Specifically the first 3?

We did not use the default ext setup that came with the UC. What was happening was the "default" ext's were still in the config file. So when trying to transfer outside the first 3 digits matched an ext that was not there, giving us the fast busy signal.

If you have access to the CLI, or even in the CCA under hte troubleshoot section, take a look at the running config and see if you have a conflict in there. We ended up changing our ext ranges to get around the problem. I did not find this actual issue until later in the rollout.

Also with the "Single number reach" feature, once you configured it on the CCA, did you actually enable it on the phone?

vanmarcke · ‎07-10-2013

Hello,

I had the same problem.

For me, setting the Caller ID to Any Number and setting the Caller ID number solved it!

Regards

Peter

unpersonal · ‎07-11-2013

Hello

Unfortunately no change... I set the setting like you (also before).

Paolo, could you explain your suggestion?

Does anyone have another idea? I’m sure it can’t be that difficult!

Regards
claude

paolo bevilacqua · ‎07-11-2013

I recommend you have this worked on by a reputable consultant or UC certified Cisco partner.

The product is not as easy it may seems to you and it is not designed to be configured by the end user.

unpersonal · ‎07-12-2013

hey

we are not end users. and i think there must be a solution for this little problem. actually this is a test project.

please any suggestions?

best regards

claude

paolo bevilacqua · ‎07-13-2013

I have indicated to you above already the steps to take. If you do not know how to do that, get someone experienced with configuring and administering Cisco routers.

unpersonal · ‎07-25-2013

hello

i debuged the line and in the log file i can read: invalid number (28)

but the number is correct set! i also used the access code.

after some other debuging i generated this log file:

i replaced the numbers in this logfile:

incoming number: source number, the number witch was used to call the customer

customer number: main number of uc540
forwarding destination: the number, witch is used as forwarding destination (as example my cell phone)

Internal_Extension_That_Should_Be_Forwarded: the number, that should be forwarded to my cell phone

-----------------------------------------------------------------------------START-----------------------------------------------------------------------------

--------------------------show logging--------------------------

Syslog logging: enabled (0 messages dropped, 7 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, 1323 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, 1922 message lines logged

Logging Source-Interface: VRF Name:

Log Buffer (1000000 bytes):

003732: Jul 25 12:55:13.340: %SYS-5-CONFIG_I: Configured from console by sysadminacc on vty1 (192.168.30.2)

003733: Jul 25 14:55:26.880: ISDN BR0/1/0 Q921: User RX <- UI sapi=0 tei=127

003734: Jul 25 14:55:26.880: ISDN BR0/1/0 Q931: SETUP pd = 8 callref = 0x62

Sending Complete

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x89

Exclusive, B1

Calling Party Number i = 0x0181, '[Incoming_Number]'

Plan:ISDN, Type:Unknown

Called Party Number i = 0xC1, '[Internal_Extension_That_Should_Be_Forwarded]'

Plan:ISDN, Type:Subscriber(local)

High Layer Compat i = 0x9181

003735: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=[Incoming_Number], Called Number=[Internal_Extension_That_Should_Be_Forwarded], Voice-Interface=0x8869A614,

Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

003736: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_INCOMING_DNIS; Incoming Dial-peer=3001

003737: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003738: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=[Incoming_Number], Called Number=[Internal_Extension_That_Should_Be_Forwarded], Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_FAX

003739: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

003740: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1

003741: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 5

003742: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/RXRULE/sed_subst: Successful substitution; pattern=[Internal_Extension_That_Should_Be_Forwarded] matchPattern=[Internal_Extension_That_Should_Be_Forwarded] replacePattern=21 replaced pattern=21

003743: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/RXRULE/regxrule_subst_num_type: Match Type = none, Replace Type = none Input Type = subscriber

003744: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/RXRULE/regxrule_subst_num_plan: Match Plan = none, Replace Plan = none Input Plan = isdn

003745: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003746: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003747: Jul 25 14:55:26.884: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003748: Jul 25 14:55:26.888: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003749: Jul 25 14:55:26.888: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003750: Jul 25 14:55:26.888: //-1/4FE2E67381E1/VTSP:(0/1/0):-1:-1:-1/vtsp_do_normal_call_setup_ind:

IC State Machine (ICSM) Added

003751: Jul 25 14:55:26.888: //-1/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_create: .

003752: Jul 25 14:55:26.888: //-1/4FE2E67381E1/DSM:(0/1/0):-1/set_echo_canceller_data: echo_cancel: 1

003753: Jul 25 14:55:26.888: //-1/4FE2E67381E1/DSM:(0/1/0):-1/set_echo_canceller_data: echo_flags: 55, echo_len: 1024

003754: Jul 25 14:55:26.888: //-1/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_set_dsp_allocated: .

003755: Jul 25 14:55:26.892: //-1/4FE2E67381E1/DSM:(0/1/0):-1/dsmp_dsmapi_reserve_resource_cb: .

003756: Jul 25 14:55:26.892: //-1/4FE2E67381E1/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=[Incoming_Number]

cisco-anitype=0

cisco-aniplan=1

cisco-anipi=0

cisco-anisi=1

dest=[Internal_Extension_That_Should_Be_Forwarded]

cisco-desttype=4

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

003757: Jul 25 14:55:26.892: //-1/4FE2E67381E1/CCAPI/cc_api_call_setup_ind_common:

Interface=0x8869A614, Call Info(

Calling Number=[Incoming_Number],(Calling Name=)(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=[Internal_Extension_That_Should_Be_Forwarded](TON=Subscriber, NPI=ISDN),

Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,

Incoming Dial-peer=3001, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1

003758: Jul 25 14:55:26.892: //-1/4FE2E67381E1/CCAPI/ccCheckClipClir:

In: Calling Number=[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

003759: Jul 25 14:55:26.892: //-1/4FE2E67381E1/CCAPI/ccCheckClipClir:

Out: Calling Number=[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

003760: Jul 25 14:55:26.892: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003761: Jul 25 14:55:26.892: :cc_get_feature_vsa malloc success

003762: Jul 25 14:55:26.892: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003763: Jul 25 14:55:26.892: cc_get_feature_vsa count is 3

003764: Jul 25 14:55:26.892: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003765: Jul 25 14:55:26.892: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308732720,feature_id:3877

003766: Jul 25 14:55:26.892: //2396/4FE2E67381E1/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=[Internal_Extension_That_Should_Be_Forwarded](TON=Subscriber, NPI=ISDN))

003767: Jul 25 14:55:26.892: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_set_elog_enable: .

003768: Jul 25 14:55:26.892: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x89AE443C; count=1

003769: Jul 25 14:55:26.892: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x894104E4

003770: Jul 25 14:55:26.892: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_indicate_call:

Source Trkgrp Route Label=ALL_BRI

003771: Jul 25 14:55:26.896: //2396/4FE2E67381E1/CCAPI/cc_process_call_setup_ind:

Event=0x8862CDA0

003772: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=[Internal_Extension_That_Should_Be_Forwarded], Peer Info Type=DIALPEER_INFO_SPEECH

003773: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=[Internal_Extension_That_Should_Be_Forwarded]

003774: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003775: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=[Internal_Extension_That_Should_Be_Forwarded], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003776: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20006

003777: Jul 25 14:55:26.896: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number [Internal_Extension_That_Should_Be_Forwarded]

003778: Jul 25 14:55:26.896: //2396/4FE2E67381E1/CCAPI/ccCallSetContext:

Context=0x899DFF54

003779: Jul 25 14:55:26.896: //2396/4FE2E67381E1/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 2396 with tag 3001 to app "_ManagedAppProcess_Default"

003780: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x89AE443C; count=1

003781: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number=[Incoming_Number] type=unknown plan=isdn numbertype=calling

003782: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 3265

003783: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 3265

003784: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/sed_subst: Successful substitution; pattern=[Incoming_Number] matchPattern=(^..........$) replacePattern=0\1 replaced pattern=0[Incoming_Number]

003785: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_type: Match Type = none, Replace Type = none Input Type = unknown

003786: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_plan: Match Plan = none, Replace Plan = none Input Plan = isdn

003787: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: xlt_number=0[Incoming_Number] xlt_type=unknown xlt_plan=isdn

003788: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number= type=UNKNOWN plan=UNKNOWN numbertype=redirect-called

003789: Jul 25 14:55:26.896: //-1/4FE2E67381E1/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

003790: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-called number not found

003791: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: No match: number= type=UNKNOWN plan=UNKNOWN

003792: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number=[Internal_Extension_That_Should_Be_Forwarded] type=subscriber plan=isdn numbertype=called

003793: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 5

003794: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 5

003795: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/sed_subst: Successful substitution; pattern=[Internal_Extension_That_Should_Be_Forwarded] matchPattern=[Internal_Extension_That_Should_Be_Forwarded] replacePattern=21 replaced pattern=21

003796: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_type: Match Type = none, Replace Type = none Input Type = subscriber

003797: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_plan: Match Plan = none, Replace Plan = none Input Plan = isdn

003798: Jul 25 14:55:26.900: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: xlt_number=21 xlt_type=subscriber xlt_plan=isdn

003799: Jul 25 14:55:26.900: //2396/4FE2E67381E1/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

003800: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003801: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003802: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003803: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003804: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003805: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003806: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003807: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003808: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003809: Jul 25 14:55:26.900: //-1/4FE2E67381E1/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003810: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=21, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003811: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003812: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003813: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003814: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003815: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=21, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

003816: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

003817: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1

003818: Jul 25 14:55:26.900: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=21, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

003819: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

003820: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1

003821: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003822: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003823: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003824: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003825: Jul 25 14:55:26.904: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003826: Jul 25 14:55:26.904: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003827: Jul 25 14:55:26.904: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003828: Jul 25 14:55:26.904: //-1/4FE2E67381E1/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003829: Jul 25 14:55:26.904: //-1/4FE2E67381E1/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=1, saf_dndb_lookup=1, dp_result=0

003830: Jul 25 14:55:26.904: //-1/4FE2E67381E1/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003831: Jul 25 14:55:26.904: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=20005, Params=0x899E1464, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

003832: Jul 25 14:55:26.904: //-1/4FE2E67381E1/RXRULE/regxrule_vp_translate: No profile found in voice port or trunk group for outgoing direction

003833: Jul 25 14:55:26.904: //-1/4FE2E67381E1/RXRULE/regxrule_vp_translate: calling_number=0[Incoming_Number] calling_octet=0x1

called_number=21 called_octet=0xC1

redirect_number= redirect_type=4294967295 redirect_plan=4294967295

003834: Jul 25 14:55:26.904: //2396/4FE2E67381E1/CCAPI/ccCheckClipClir:

In: Calling Number=0[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

003835: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/ccCheckClipClir:

Out: Calling Number=0[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

003836: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Destination Pattern=21$, Called Number=21, Digit Strip=TRUE

003837: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Calling Number=0[Incoming_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=21(TON=Subscriber, NPI=ISDN),

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=TRUE,

Guid=4FE2E673-F460-11E2-81E1-ACF2C59E4440, Outgoing Dial-peer=20005

003838: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=0[Incoming_Number]

cisco-anitype=0

cisco-aniplan=1

cisco-anipi=0

cisco-anisi=1

dest=21

cisco-desttype=4

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

003839: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x8971AFBC, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=0[Incoming_Number],(Calling Name=)(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=21(TON=Subscriber, NPI=ISDN), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20005, Call Count On=FALSE,

Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=)

003840: Jul 25 14:55:26.908: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003841: Jul 25 14:55:26.908: :cc_get_feature_vsa malloc success

003842: Jul 25 14:55:26.908: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003843: Jul 25 14:55:26.908: cc_get_feature_vsa count is 4

003844: Jul 25 14:55:26.908: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003845: Jul 25 14:55:26.908: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308735632,feature_id:3878

003846: Jul 25 14:55:26.908: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_spi_event_handler:

Event Category=1, Event=176

003847: Jul 25 14:55:26.908: //2397/4FE2E67381E1/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

003848: Jul 25 14:55:26.908: //2397/4FE2E67381E1/CCAPI/ccCallSetContext:

Context=0x899E1414

003849: Jul 25 14:55:26.908: //2396/4FE2E67381E1/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=20005

003850: Jul 25 14:55:26.908: htsp_timer_stop3

003851: Jul 25 14:55:26.908: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_create: .

003852: Jul 25 14:55:26.908: //2397/4FE2E67381E1/DSM:(50/0/297):-1/set_echo_canceller_data: echo_cancel: 1

003853: Jul 25 14:55:26.908: //2397/4FE2E67381E1/DSM:(50/0/297):-1/set_echo_canceller_data: echo_flags: 23, echo_len: 64

003854: Jul 25 14:55:26.908: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_set_dsp_allocated: .

003855: Jul 25 14:55:26.912: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmp_dsmapi_reserve_resource_cb: .

003856: Jul 25 14:55:26.912: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_set_elog_enable: .

003857: Jul 25 14:55:26.912: //2397/4FE2E67381E1/VTSP:(50/0/297):-1:0:2369/vtsp_create_call_active_on_setup_req:

Target Carrier ID=

003858: Jul 25 14:55:26.912: //2397/4FE2E67381E1/VTSP:(50/0/297):-1:0:2369/vtsp_create_call_active_on_setup_req:

Source Trkgrp Route Label=ALL_BRIhtsp_setup_req

003859: Jul 25 14:55:26.912: //2397/4FE2E67381E1/CCAPI/cc_api_update_call_info:

Interface=0x8971AFBC, Call Id=0x95D

003860: Jul 25 14:55:26.912: //2397/4FE2E67381E1/DSPAPI/[]/dsp_get_multimedia_capabilities:

003861: Jul 25 14:55:26.912: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_implement_feature:

003862: Jul 25 14:55:26.912: dsp_stream_mgr_implement_feature: feature id = 6

003863: Jul 25 14:55:26.912: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_implement_feature: Illegal callerid parameters, callerid_params = 0x0

003864: Jul 25 14:55:26.912: htsp_process_event: [50/0/297.1, EFXS_ONHOOK, E_HTSP_SETUP_REQ]efxs_onhook_setup

003865: Jul 25 14:55:26.916: htsp_ephone_start_caller_id_tx calling num=0[Incoming_Number] calling name = called num=21 orig called num=

003866: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003867: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003868: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003869: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Incoming_Number], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003870: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=123

2: Dial-peer Tag=83

003871: Jul 25 14:55:26.916: [50/0/297.1] set signal state = 0x0 timestamp = 0

003872: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003873: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003874: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003875: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003876: Jul 25 14:55:26.916: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003877: Jul 25 14:55:26.916: efxs_onhook_setup: local target is available

htsp_alert

003878: Jul 25 14:55:26.916: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_reinit_platform_info: .

003879: Jul 25 14:55:26.916: //2397/4FE2E67381E1/CCAPI/cc_api_call_proceeding:

Interface=0x8971AFBC, Progress Indication=NULL(0)

003880: Jul 25 14:55:26.924: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmp_dsmapi_reserve_resource_cb: .

003881: Jul 25 14:55:26.924: //2397/4FE2E67381E1/CCAPI/cc_api_call_alert:

Interface=0x8971AFBC, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

003882: Jul 25 14:55:26.924: //2397/4FE2E67381E1/CCAPI/cc_api_call_alert:

Call Entry(Retry Count=0, Responsed=TRUE)

003883: Jul 25 14:55:26.924: ISDN BR0/1/0 Q921: L2_EstablishDataLink: sending SABME

003884: Jul 25 14:55:26.924: ISDN BR0/1/0 Q921: User TX -> SABMEp sapi=0 tei=75

003885: Jul 25 14:55:26.928: //2396/4FE2E67381E1/CCAPI/ccCallAlert:

Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)

003886: Jul 25 14:55:26.928: //2396/4FE2E67381E1/CCAPI/ccCallAlert:

Call Entry(Responsed=TRUE, Alert Sent=TRUE)

003887: Jul 25 14:55:26.928: //2396/4FE2E67381E1/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Ring Back,

Tone Direction=Network, Params=0x0, Call Id=2396

003888: Jul 25 14:55:26.928: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_play_tone_control: Tone Ring Back generated in direction Network

003889: Jul 25 14:55:26.928: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_dsp_cp_dual_tone_on: cpPtr->off_time_third: 0, sl_delay: 0

003890: Jul 25 14:55:26.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003891: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003892: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003893: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003894: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003895: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003896: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003897: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003898: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003899: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=83

2: Dial-peer Tag=123

003900: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003901: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003902: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003903: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Incoming_Number], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003904: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=123

2: Dial-peer Tag=83

003905: Jul 25 14:55:26.932: //2397/4FE2E67381E1/CCAPI/ccCallFeature:

Feature Type=25, Call Id=2397

003906: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003907: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003908: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003909: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003910: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=123

2: Dial-peer Tag=83

003911: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003912: Jul 25 14:55:26.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003913: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003914: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

003915: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003916: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003917: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003918: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003919: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003920: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003921: Jul 25 14:55:26.936: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_pak_control: on = 0

003922: Jul 25 14:55:26.936: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_play_tone_control: Tone Ring Back generated in direction Network

003923: Jul 25 14:55:26.936: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_dsp_cp_dual_tone_on: cpPtr->off_time_third: 0, sl_delay: 0htsp_call_feature:feature 25

htsp_call_feature: caller id enable 0x3 call_connected 0

003924: Jul 25 14:55:26.936: htsp_process_event: [50/0/297.1, EFXS_WAIT_OFFHOOK, E_HTSP_CALLERID_WAITING]

003925: Jul 25 14:55:26.936: efxs_callerid_update

003926: Jul 25 14:55:26.936: efxs_callerid_update process caller_id_string

003927: Jul 25 14:55:26.936: efxs_callerid_update process caller_id_string OK

003928: Jul 25 14:55:26.936: efxs_callerid_update number= [0[Incoming_Number]] name= []

003929: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003930: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003931: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003932: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Incoming_Number], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003933: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=83

2: Dial-peer Tag=123

003934: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003935: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003936: Jul 25 14:55:26.936: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003937: Jul 25 14:55:26.940: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003938: Jul 25 14:55:26.940: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003939: Jul 25 14:55:26.940: ISDN BR0/1/0 Q921: User RX <- UAf sapi=0 tei=75

003940: Jul 25 14:55:26.940: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_init:

003941: Jul 25 14:55:26.940: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_open_service:

G711_U (5)

003942: Jul 25 14:55:26.940: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

003943: Jul 25 14:55:26.940: 6 parameters

[0] ENCAP RTP: Tx SSRC=0, Rx SSRC=0, Tx VPXCC=0, Rx VPXCC=0

IFP payload type=122, SID support=1, TSE payload=101, Sequence number start=0

Redundancy=0, ClearChannel payload type=0, Fax payload type=0

Alaw PCM switchover=0, MuLAW PCM switchover=0

DTMF payload type=0, NTE receive payload type=101

LMR tone payload type=0, NTE tone payload type=0

Dynamic payload=255, Codec=g711ulaw

[1] PO_JITTER: mode=2 initial=60(ms) max=1000(ms) min=40(ms) fax_nom=300(ms)

[2] INFO_FIELD_SIZE: 160 bytes

[3] IDLE_CODE_DET: Enable=0, Code=0x0, Duration=6000(ms)

[4] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[5] CNG: 1

003944: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_config_params:

3 parameters

003945: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_start_service:

G711_U (5)

003946: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_call_progress_detect:

003947: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

003948: Jul 25 14:55:26.944: 5 parameters

[0] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[1] GAIN: Input=0.0(dB), Output=0.0(dB)

[2] AGC CONTROL: Input=off, Output=off

[3] DIGIT_RELAY: 2

[4] VAD: Enable=0, Threshold=-38(dBm)

003949: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

003950: Jul 25 14:55:26.944: 1 parameter

[0] PAK_SUPPRESS: 1

003951: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_tone_on:

Tone ID=1, Direction=1, Num frequencies=1

Frequency(hz): a=425 b=0, Amplitude(dB): a=-10.0 b=0.0

Cadence1(ms): ON=1000 OFF=4000

Cadence2(ms): ON=0 OFF=0

Cadence3(ms): ON=0 OFF=0

Cadence4(ms): ON=0 OFF=0

Frequency(hz): a2=33159 b2=63860 a3=34079 b3=23148 a4=35201 b4=49536

ITO option group: 1

003952: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_tone_off:

003953: Jul 25 14:55:26.944: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_tone_on:

Tone ID=1, Direction=1, Num frequencies=1

Frequency(hz): a=425 b=0, Amplitude(dB): a=-10.0 b=0.0

Cadence1(ms): ON=1000 OFF=4000

Cadence2(ms): ON=0 OFF=0

Cadence3(ms): ON=0 OFF=0

Cadence4(ms): ON=0 OFF=0

Frequency(hz): a2=33159 b2=63860 a3=33792 b3=46636 a4=33040 b4=18088

ITO option group: 1

003954: Jul 25 14:55:26.948: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=0 nr=0

003955: Jul 25 14:55:26.948: ISDN BR0/1/0 Q931: CALL_PROC pd = 8 callref = 0xE2

Channel ID i = 0x89

Exclusive, B1

003956: Jul 25 14:55:26.964: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=1

003957: Jul 25 14:55:26.964: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=1 nr=0

003958: Jul 25 14:55:26.964: ISDN BR0/1/0 Q931: ALERTING pd = 8 callref = 0xE2

Progress Ind i = 0x8188 - In-band info or appropriate now available

003959: Jul 25 14:55:26.984: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=2

003960: Jul 25 14:55:29.924: //2397/4FE2E67381E1/VTSP:(50/0/297):-1:0:2369/vtsp_cc_call_disconnected:

Cause Value=19

003961: Jul 25 14:55:29.924: //2397/4FE2E67381E1/CCAPI/cc_api_call_disconnected:

Cause Value=19, Interface=0x8971AFBC, Call Id=2397

003962: Jul 25 14:55:29.924: //2397/4FE2E67381E1/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=19, Retry Count=0)

003963: Jul 25 14:55:29.924: //2396/4FE2E67381E1/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

003964: Jul 25 14:55:29.924: //2397/4FE2E67381E1/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

003965: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=21

003966: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003967: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003968: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003969: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003970: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003971: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Forwarding_Destination], Peer Info Type=DIALPEER_INFO_SPEECH

003972: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Forwarding_Destination]

003973: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003974: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Forwarding_Destination], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003975: Jul 25 14:55:29.924: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=124

2: Dial-peer Tag=93

003976: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:

type=6 Original Called Number=21, Called Number=21, Calling Number=0[Incoming_Number], Calling DN=-1 Calling Id=2396,

Redirect Number=0[Forwarding_Destination], Redirect Reason=2

003977: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

003978: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

003979: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003980: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Incoming_Number], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003981: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=123

2: Dial-peer Tag=83

003982: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Forwarding_Destination], Peer Info Type=DIALPEER_INFO_SPEECH

003983: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Forwarding_Destination]

003984: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003985: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Forwarding_Destination], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003986: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=93

2: Dial-peer Tag=124

003987: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Forwarding_Destination], Peer Info Type=DIALPEER_INFO_SPEECH

003988: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Forwarding_Destination]

003989: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003990: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Forwarding_Destination], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003991: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=93

2: Dial-peer Tag=124

003992: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:

type=6 redirecting_number=21

003993: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=21, Peer Info Type=DIALPEER_INFO_SPEECH

003994: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=21

003995: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

003996: Jul 25 14:55:29.928: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=21, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

003997: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=20005

003998: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

003999: Jul 25 14:55:29.932: :cc_get_feature_vsa malloc success

004000: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004001: Jul 25 14:55:29.932: cc_get_feature_vsa count is 5

004002: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004003: Jul 25 14:55:29.932: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308736304,feature_id:3879

004004: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004005: Jul 25 14:55:29.932: feature_vsa is 899C7728,featname is 0

004006: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004007: Jul 25 14:55:29.932: :cc_get_feature_vsa malloc success

004008: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004009: Jul 25 14:55:29.932: cc_get_feature_vsa count is 6

004010: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004011: Jul 25 14:55:29.932: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308734960,feature_id:3880

004012: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004013: Jul 25 14:55:29.932: : Generating new featurevsa,inserting it

004014: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004015: Jul 25 14:55:29.932: feature call forward featname is 3

004016: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004017: Jul 25 14:55:29.932: feature_vsa is 899C8288,featname is 0

004018: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004019: Jul 25 14:55:29.932: :cc_get_feature_vsa malloc success

004020: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004021: Jul 25 14:55:29.932: cc_get_feature_vsa count is 7

004022: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004023: Jul 25 14:55:29.932: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308733616,feature_id:3881

004024: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004025: Jul 25 14:55:29.932: : Generating new featurevsa,inserting it

004026: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004027: Jul 25 14:55:29.932: feature call forward featname is 3

004028: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004029: Jul 25 14:55:29.932: :cc_free_feature_vsa freeing 899C8528

004030: Jul 25 14:55:29.932: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004031: Jul 25 14:55:29.932: vsacount in free is 6

004032: Jul 25 14:55:29.932: //2396/4FE2E67381E1/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=FALSE, Tone=Null,

Tone Direction=Network, Params=0x0, Call Id=2396

004033: Jul 25 14:55:29.932: //2397/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=19)

004034: Jul 25 14:55:29.932: //2397/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=19, Call Entry(Responsed=TRUE, Cause Value=19)

004035: Jul 25 14:55:29.932: //2397/4FE2E67381E1/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

004036: Jul 25 14:55:29.932: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_get_levels_sync: .

004037: Jul 25 14:55:29.936: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_initiate_close: .

004038: Jul 25 14:55:29.936: htsp_timer_stop3

004039: Jul 25 14:55:29.936: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_tone_off:

004040: Jul 25 14:55:29.936: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmp_dsmapi_stats_cb: .

004041: Jul 25 14:55:29.936: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmp_dsmapi_stats_cb: .

004042: Jul 25 14:55:29.936: htsp_process_event: [50/0/297.1, EFXS_WAIT_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_waitoff_release

004043: Jul 25 14:55:29.936: [50/0/297.1] set signal state = 0x4 timestamp = 0

004044: Jul 25 14:55:29.940: //2397/4FE2E67381E1/VTSP:(50/0/297):-1:0:2369/vtsp_do_call_history:

Coder Rate=-1

004045: Jul 25 14:55:29.940: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsp_stream_mgr_destroy: .

004046: Jul 25 14:55:29.940: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsm_delete_all_streams: .

004047: Jul 25 14:55:29.940: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmapi_accept_modem_passthrough_session: : dmgr: 86698CE0, active sessions 0, max sessions: 16 rejected sessions till now: 0

004048: Jul 25 14:55:29.944: //2397/4FE2E67381E1/DSM:(50/0/297):-1/dsmp_dsmapi_reserve_resource_cb: .

004049: Jul 25 14:55:29.944: //2397/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x8971AFBC, Tag=0x0, Call Id=2397,

Call Entry(Disconnect Cause=19, Voice Class Cause Code=0, Retry Count=0)

004050: Jul 25 14:55:29.944: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x0

004051: Jul 25 14:55:29.944: //2397/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

004052: Jul 25 14:55:29.944: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004053: Jul 25 14:55:29.944: :cc_free_feature_vsa freeing 899C7AA8

004054: Jul 25 14:55:29.944: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004055: Jul 25 14:55:29.944: vsacount in free is 5

004056: Jul 25 14:55:29.944: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004057: Jul 25 14:55:29.944: vsacount in free is 4

004058: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004059: Jul 25 14:55:29.948: :cc_get_feature_vsa malloc success

004060: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004061: Jul 25 14:55:29.948: cc_get_feature_vsa count is 5

004062: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004063: Jul 25 14:55:29.948: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308735632,feature_id:3882

004064: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004065: Jul 25 14:55:29.948: feature call forward featname is 3

004066: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004067: Jul 25 14:55:29.948: :cc_free_feature_vsa freeing 899C8288

004068: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004069: Jul 25 14:55:29.948: vsacount in free is 4

004070: Jul 25 14:55:29.948: //2396/4FE2E67381E1/CCAPI/ccGetTBCTCap:

TGRM TBCT Enabled; TBCT Capability=0

004071: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

004072: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

004073: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

004074: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=0[Incoming_Number], saf_enabled=0, saf_dndb_lookup=1, dp_result=0

004075: Jul 25 14:55:29.948: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=123

2: Dial-peer Tag=83

004076: Jul 25 14:55:29.948: //2396/4FE2E67381E1/CCAPI/ccGetTBCTCap:

TGRM TBCT Enabled; TBCT Capability=0

004077: Jul 25 14:55:29.952: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=0[Forwarding_Destination], Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

004078: Jul 25 14:55:29.952: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=NO_MATCH(-1) After All Match Rules Attempt

004079: Jul 25 14:55:29.952: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1

004080: Jul 25 14:55:29.952: //-1/51B70A649BE7/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Forwarding_Destination], Peer Info Type=DIALPEER_INFO_SPEECH

004081: Jul 25 14:55:29.952: //-1/51B70A649BE7/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Forwarding_Destination]

004082: Jul 25 14:55:29.952: //-1/51B70A649BE7/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

004083: Jul 25 14:55:29.952: //-1/51B70A649BE7/DPM/dpMatchSafModulePlugin:

dialstring=0[Forwarding_Destination], saf_enabled=1, saf_dndb_lookup=1, dp_result=0

004084: Jul 25 14:55:29.952: //-1/51B70A649BE7/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=124

2: Dial-peer Tag=93

004085: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_translate_exist_internal: no rule for callback-number number

004086: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: number=0[Incoming_Number] type=unknown plan=isdn numbertype=calling

004087: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

004088: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_profile_match_internal: Error: ruleset for calling number not found

004089: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: No match: number=0[Incoming_Number] type=unknown plan=isdn

004090: Jul 25 14:55:29.952: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: number=0[Forwarding_Destination] type=subscriber plan=isdn numbertype=called

004091: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 1112

004092: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_match_internal: Matched with rule 1 in ruleset 1112

004093: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/sed_subst: Successful substitution; pattern=0[Forwarding_Destination] matchPattern=^0 replacePattern= replaced pattern=[Forwarding_Destination]

004094: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_subst_num_type: Match Type = none, Replace Type = none Input Type = subscriber

004095: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_subst_num_plan: Match Plan = none, Replace Plan = none Input Plan = isdn

004096: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: xlt_number=[Forwarding_Destination] xlt_type=subscriber xlt_plan=isdn

004097: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: number= type=subscriber plan=isdn numbertype=redirect-target

004098: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

004099: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-target number not found

004100: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: No match: number= type=subscriber plan=isdn

004101: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: number=21 type=unknown plan=unknown numbertype=redirect-called

004102: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

004103: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-called number not found

004104: Jul 25 14:55:29.956: //-1/51B70A649BE7/RXRULE/regxrule_profile_translate_internal: No match: number=21 type=unknown plan=unknown

004105: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=124, Params=0x899DD624, Progress Indication=NULL(0)

004106: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/cc_fill_tg_params:

Not a cic call

004107: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: stack=0x89AE443C; count=2

004108: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_get_profile_from_trunkgroup_internal: Found profile PROFILE_ALL_BRI defined on trunk group

004109: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number=0[Incoming_Number] type=unknown plan=isdn numbertype=calling

004110: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 15 in ruleset 6

004111: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Matched with rule 15 in ruleset 6

004112: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/sed_subst: Successful substitution; pattern=0[Incoming_Number] matchPattern=^.*$ replacePattern=[Customer_Main_Number] replaced pattern=[Customer_Main_Number]

004113: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_type: Match Type = none, Replace Type = none Input Type = unknown

004114: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_subst_num_plan: Match Plan = none, Replace Plan = none Input Plan = isdn

004115: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: xlt_number=[Customer_Main_Number] xlt_type=unknown xlt_plan=isdn

004116: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number=[Forwarding_Destination] type=subscriber plan=isdn numbertype=called

004117: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

004118: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Error: ruleset for called number not found

004119: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: No match: number=[Forwarding_Destination] type=subscriber plan=isdn

004120: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: number=21 type=unknown plan=unknown numbertype=redirect-called

004121: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_get_RegXrule: Invalid translation ruleset tag=0

004122: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_match_internal: Error: ruleset for redirect-called number not found

004123: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_profile_translate_internal: No match: number=21 type=unknown plan=unknown

004124: Jul 25 14:55:29.956: //-1/4FE2E67381E1/RXRULE/regxrule_tg_translate: calling_number=[Customer_Main_Number] calling_octet=0x1

called_number=[Forwarding_Destination] called_octet=0xC1

redirect_number=21 redirect_type=0 redirect_plan=0

004125: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Trunk Group Select Interface Success;

Interface=0x8869A614, Selected Interface=2, Selected DSL=2

004126: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCheckClipClir:

In: Calling Number=[Customer_Main_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

004127: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCheckClipClir:

Out: Calling Number=[Customer_Main_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed)

004128: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Destination Pattern=007[46789]......., Called Number=[Forwarding_Destination], Digit Strip=TRUE

004129: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Calling Number=[Customer_Main_Number](TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=[Forwarding_Destination](TON=Subscriber, NPI=ISDN),

Redirect Number=21, Display Info=

Account Number=, Final Destination Flag=TRUE,

Guid=51B70A64-F460-11E2-9BE7-AFDEE4C60810, Outgoing Dial-peer=124

004130: Jul 25 14:55:29.956: //2396/4FE2E67381E1/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=[Customer_Main_Number]

cisco-anitype=0

cisco-aniplan=1

cisco-anipi=0

cisco-anisi=1

dest=[Forwarding_Destination]

cisco-desttype=4

cisco-destplan=1

cisco-rdie=73

cisco-rdn=21

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=2 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

004131: Jul 25 14:55:29.960: //2396/4FE2E67381E1/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x8869A614, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=[Customer_Main_Number],(Calling Name=)(TON=Unknown, NPI=ISDN, Screening=User, Passed, Presentation=Allowed),

Called Number=[Forwarding_Destination](TON=Subscriber, NPI=ISDN), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=124, Call Count On=FALSE,

Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=ALL_BRI, tg_label_flag=2, Application Call Id=)

004132: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004133: Jul 25 14:55:29.960: :cc_get_feature_vsa malloc success

004134: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004135: Jul 25 14:55:29.960: cc_get_feature_vsa count is 5

004136: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004137: Jul 25 14:55:29.960: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308735632,feature_id:3883

004138: Jul 25 14:55:29.960: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_spi_event_handler:

Event Category=1, Event=176

004139: Jul 25 14:55:29.960: //2398/4FE2E67381E1/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

004140: Jul 25 14:55:29.960: //-1/4FE2E67381E1/RXRULE/regxrule_stack_push_RegXruleNumInfo_internal: Success in ccTDUtilSetDataInstance

004141: Jul 25 14:55:29.960: //2396/4FE2E67381E1/CCAPI/ccCallSetupRequest:

Trunk Group Call Setting;

Call Entry(Retry Count=0, Voice Class Cause Code=0)

004142: Jul 25 14:55:29.960: //2398/4FE2E67381E1/CCAPI/ccCallSetContext:

Context=0x899DD5D4

004143: Jul 25 14:55:29.960: //2396/4FE2E67381E1/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=124

004144: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004145: Jul 25 14:55:29.960: feature call forward featname is 3

004146: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004147: Jul 25 14:55:29.960: feature_vsa is 899C8288,featname is 0

004148: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004149: Jul 25 14:55:29.960: :cc_get_feature_vsa malloc success

004150: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004151: Jul 25 14:55:29.960: cc_get_feature_vsa count is 6

004152: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

004153: Jul 25 14:55:29.960: :FEATURE_VSA attributes are: feature_name:0,feature_time:2308733616,feature_id:3884

004154: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004155: Jul 25 14:55:29.960: : Generating new featurevsa,inserting it

004156: Jul 25 14:55:29.960: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

004157: Jul 25 14:55:29.960: feature call forward featname is 3

004158: Jul 25 14:55:29.960: //2398/xxxxxxxxxxxx/VTSP:(0/1/0):-1:-1:-1/vtsp_og_call_req:

Target Trkgrp Route Label=ALL_BRI

004159: Jul 25 14:55:29.960: //2396/4FE2E67381E1/CCAPI/cc_api_modify_tgt_cid_call_active_record:

Target Trkgrp Route Label=ALL_BRI, tg_label_flag=TRUE, Call Id=0x95C

004160: Jul 25 14:55:29.960: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_create: .

004161: Jul 25 14:55:29.960: //2398/51B70A649BE7/DSM:(0/1/0):-1/set_echo_canceller_data: echo_cancel: 1

004162: Jul 25 14:55:29.960: //2398/51B70A649BE7/DSM:(0/1/0):-1/set_echo_canceller_data: echo_flags: 55, echo_len: 1024

004163: Jul 25 14:55:29.964: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmp_dsmapi_reserve_resource_cb: .

004164: Jul 25 14:55:29.964: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_set_elog_enable: .

004165: Jul 25 14:55:29.964: //2398/51B70A649BE7/VTSP:(0/1/0):-1:0:0/vtsp_create_call_active_on_setup_req:

Target Trkgrp Route Label=ALL_BRI

004166: Jul 25 14:55:29.964: //2398/51B70A649BE7/VTSP:(0/1/0):-1:0:0/vtsp_create_call_active_on_setup_req:

Source Trkgrp Route Label=ALL_BRI

004167: Jul 25 14:55:29.968: ISDN BR0/1/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x1, Calling num [Customer_Main_Number]

004168: Jul 25 14:55:29.968: ISDN BR0/1/0 Q931: Sending SETUP callref = 0x0042 callID = 0x8189 switch = basic-net3 interface = User

004169: Jul 25 14:55:29.968: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=2 nr=0

004170: Jul 25 14:55:29.968: ISDN BR0/1/0 Q931: SETUP pd = 8 callref = 0x42

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x8A

Exclusive, B2

Calling Party Number i = 0x0181, '[Customer_Main_Number]'

Plan:ISDN, Type:Unknown

Called Party Number i = 0xC1, '[Forwarding_Destination]'

Plan:ISDN, Type:Subscriber(local)

High Layer Compat i = 0x9181

Sending Complete

004171: Jul 25 14:55:30.004: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=3

004172: Jul 25 14:55:30.236: ISDN BR0/1/0 Q921: User RX <- INFO sapi=0 tei=75, ns=0 nr=3

004173: Jul 25 14:55:30.236: ISDN BR0/1/0 Q931: CALL_PROC pd = 8 callref = 0xC2

Channel ID i = 0x8A

Exclusive, B2

004174: Jul 25 14:55:30.236: ISDN BR0/1/0 Q921: User TX -> RR sapi=0 tei=75 nr=1

004175: Jul 25 14:55:30.236: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_reinit_platform_info: .

004176: Jul 25 14:55:30.236: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_set_dsp_allocated: .

004177: Jul 25 14:55:30.236: //2398/4FE2E67381E1/CCAPI/cc_api_call_proceeding:

Interface=0x8869A614, Progress Indication=NULL(0)

004178: Jul 25 14:55:30.240: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmp_dsmapi_reserve_resource_cb: .

004179: Jul 25 14:55:30.260: ISDN BR0/1/0 Q921: User RX <- INFO sapi=0 tei=75, ns=1 nr=3

004180: Jul 25 14:55:30.260: ISDN BR0/1/0 Q931: DISCONNECT pd = 8 callref = 0xC2

Cause i = 0x829C - Invalid number format (incomplete number)

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Progress Ind i = 0x8288 - In-band info or appropriate now available

004181: Jul 25 14:55:30.260: ISDN BR0/1/0 Q921: User TX -> RR sapi=0 tei=75 nr=2

004182: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: isdnAsn1Inv: error: op 35

004183: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: isdnAsn1Component: ERROR

004184: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: process_facility_ie: Decode of facility IE operation 35 failed1

004185: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: isdnAsn1Inv: error: op 35

004186: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: isdnAsn1Component: ERROR

004187: Jul 25 14:55:30.264: ISDN BR0/1/0 **ERROR**: process_facility_ie: Decode of facility IE operation 35 failed1

004188: Jul 25 14:55:30.264: //2398/4FE2E67381E1/CCAPI/cc_api_call_facility:

Interface=0x8869A614, Call Id=2398

004189: Jul 25 14:55:30.264: //2398/4FE2E67381E1/CCAPI/cc_api_call_disc_prog_ind:

Cause Value=28, Progress Indication=INBAND(8), Interface=0x8869A614

004190: Jul 25 14:55:30.264: //2398/4FE2E67381E1/CCAPI/cc_api_call_disc_prog_ind:

Event(Progress Indication=0x8), Call Entry(Responsed=TRUE)

004191: Jul 25 14:55:30.268: //2396/4FE2E67381E1/CCAPI/ccCallFacility:

Call Id=2396

004192: Jul 25 14:55:30.268: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=3 nr=2

004193: Jul 25 14:55:30.268: ISDN BR0/1/0 Q931: FACILITY pd = 8 callref = 0xE2

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Facility i = 0x9E810003677464000000164641432C0D0A50524E2C6973646E2A2C2C2C0D0A0D0A

- Unknown Service Discriminator 158

004194: Jul 25 14:55:30.268: //2396/4FE2E67381E1/CCAPI/ccCallDiscProgInd:

Cause Value=28, Progress Indication=INBAND(8)

004195: Jul 25 14:55:30.268: //2396/4FE2E67381E1/CCAPI/ccCallDiscProgInd:

Call Entry(Responsed=TRUE, Disconnect Cause=28)

004196: Jul 25 14:55:30.268: //2396/4FE2E67381E1/CCAPI/ccConferenceCreate:

(confID=0x890120A8, callID1=0x95C, gcid=4FE2E673-F46011E2-81E1ACF2-C59E4440, tag=0x0)

004197: Jul 25 14:55:30.268: //2398/4FE2E67381E1/CCAPI/ccConferenceCreate:

(confID=0x890120A8, callID2=0x95E, gcid=4FE2E673-F46011E2-81E1ACF2-C59E4440, tag=0x0)

004198: Jul 25 14:55:30.268: //2396/4FE2E67381E1/CCAPI/ccConferenceCreate:

Conference Id=0x890120A8, Call Id1=2396, Call Id2=2398, Tag=0x0

004199: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: .

004200: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_bridge:

004201: Jul 25 14:55:30.272: dsp_stream_mgr_bridge, src_call_id 2396, dst_call_id 2398

004202: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: creating packet streams

004203: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_insert_conn_array_entry: dmgr->connArr.count = 2

004204: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: doing DSP less hairpin

004205: Jul 25 14:55:30.272: //2396/4FE2E67381E1/CCAPI/cc_api_bridge_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2396,

Destination Call Id=2398, Disposition=0x0, Tag=0xFFFFFFFF

004206: Jul 25 14:55:30.272: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_set_feature_parameters: Fax Relay Mode = 4, Primary Fax Protocol = 1

Fax fallback protocol = 4, T.38 Fax Version 0

004207: Jul 25 14:55:30.272: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_dsm_save_tty_config:

Save TTY configuration

004208: Jul 25 14:55:30.272: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

004209: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: .

004210: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_bridge:

004211: Jul 25 14:55:30.272: dsp_stream_mgr_bridge, src_call_id 2398, dst_call_id 2396

004212: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: creating packet streams

004213: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_insert_conn_array_entry: dmgr->connArr.count = 2

004214: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_bridge: doing DSP less hairpin

004215: Jul 25 14:55:30.272: //2398/4FE2E67381E1/CCAPI/cc_api_bridge_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2398,

Destination Call Id=2396, Disposition=0x0, Tag=0xFFFFFFFF

004216: Jul 25 14:55:30.272: //2396/4FE2E67381E1/CCAPI/cc_generic_bridge_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2398,

Destination Call Id=2396, Disposition=0x0, Tag=0xFFFFFFFF

004217: Jul 25 14:55:30.272: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_set_feature_parameters: Fax Relay Mode = 4, Primary Fax Protocol = 1

Fax fallback protocol = 4, T.38 Fax Version 0

004218: Jul 25 14:55:30.272: //2398/51B70A649BE7/VTSP:(0/1/0):-1:1:3/vtsp_dsm_save_tty_config:

Save TTY configuration

004219: Jul 25 14:55:30.276: //2398/51B70A649BE7/VTSP:(0/1/0):-1:1:3/vtsp_dsm_set_tty_feat_param:

Set TTY feat parameters

004220: Jul 25 14:55:30.276: //2396/4FE2E67381E1/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x2CE, Destination Call Id=2398)

004221: Jul 25 14:55:30.276: //2398/4FE2E67381E1/CCAPI/ccConferenceCreate:

Call Entry(Conference Id=0x2CE, Destination Call Id=2396)

004222: Jul 25 14:55:30.276: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_do_caps_ind: .

004223: Jul 25 14:55:30.276: //2396/4FE2E67381E1/CCAPI/cc_api_caps_ind:

Destination Interface=0x8869A614, Destination Call Id=2398, Source Call Id=2396,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,

Modem=0x2, Codec Bytes=160, Signal Type=2)

004224: Jul 25 14:55:30.276: //2396/4FE2E67381E1/CCAPI/cc_api_caps_ind:

Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

Playout Max=1000(ms), Fax Nom=300(ms))

004225: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=0[Incoming_Number], Peer Info Type=DIALPEER_INFO_SPEECH

004226: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=0[Incoming_Number]

004227: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

004228: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

004229: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=83

2: Dial-peer Tag=123

004230: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=[Forwarding_Destination], Peer Info Type=DIALPEER_INFO_SPEECH

004231: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=[Forwarding_Destination]

004232: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1)

004233: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1

004234: Jul 25 14:55:30.276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeers:

Result=NO_MATCH(-1)

004235: Jul 25 14:55:30.276: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_do_caps_ind: .

004236: Jul 25 14:55:30.276: //2398/4FE2E67381E1/CCAPI/cc_api_caps_ind:

Destination Interface=0x8869A614, Destination Call Id=2396, Source Call Id=2398,

Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2,

Modem=0x2, Codec Bytes=160, Signal Type=2)

004237: Jul 25 14:55:30.276: //2398/4FE2E67381E1/CCAPI/cc_api_caps_ind:

Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms),

Playout Max=1000(ms), Fax Nom=300(ms))

004238: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: src_call_id = 2398, dst_call_id = 2396, is_cap_ack = 0

004239: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps in caps indication:

004240: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004241: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004242: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004243: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xF90

004244: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps after caps negotiation:

004245: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004246: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004247: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004248: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xF90

004249: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: packet streams already created during bridging

004250: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps in caps indication:

004251: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004252: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004253: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004254: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xF90

004255: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps negotiation: ephone-ephone

004256: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps after caps negotiation:

004257: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004258: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004259: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004260: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xF90

004261: Jul 25 14:55:30.280: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: Fax CM and ANS suppression is enabled, cm suppress 1 ans suppress 0

004262: Jul 25 14:55:30.280: //2398/4FE2E67381E1/CCAPI/cc_api_caps_ack:

Destination Interface=0x8869A614, Destination Call Id=2396, Source Call Id=2398,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=3984)

004263: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: src_call_id = 2396, dst_call_id = 2398, is_cap_ack = 1

004264: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: src_call_id = 2396, dst_call_id = 2398, is_cap_ack = 0

004265: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps in caps indication:

004266: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004267: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004268: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004269: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xEC2

004270: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps after caps negotiation:

004271: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004272: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004273: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004274: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xEC2

004275: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: packet streams already created during bridging

004276: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps in caps indication:

004277: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004278: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004279: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004280: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xEC2

004281: Jul 25 14:55:30.280: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps negotiation: ephone-ephone

004282: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_ind_negotiation: Caps after caps negotiation:

004283: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: playout: [ mode:1,init:60, min:40, max:1000]. data_mode:0

004284: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: RTP PT:NTE[0],NSE[100],FaxInd[96],FaxAck[97],CiscoDTMF[121],FaxRelay[122],CASsig[123],TTY[119],ClearChan[125],PCMu[0],PCMa[8]LmrTonePayload[0], NteTonePayload[0],Codec[1], TxDynamicPayload[0], RxDynamicPayload[0]

004285: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_caps_dump: passthrough: cap_modem_proto 0,cap_modem_codec 0, cap_modem_redundancy 0, payload 0, modem_relay 0, gw-xid 1, sprt_retries 12, sse pt 118, sprt pt 120, t1 1000, retries 3, v14_rx_ht 50, v14_tx_ht 20, v14_tx_hc 16, ec 1, phy 1

004286: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_debug_caps_encap: Encap 1, Vad 2, Codec 0x1, CodecBytes 160,

FaxRate 2, FaxBytes 20, FaxNsf 0xAD0051

T.38 Fax Version 0, SignalType 2

DtmfRelay 1, Modem 2, SeqNumStart 0xEC2

004287: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: Fax CM and ANS suppression is enabled, cm suppress 1 ans suppress 0

004288: Jul 25 14:55:30.284: //2396/4FE2E67381E1/CCAPI/cc_api_caps_ack:

Destination Interface=0x8869A614, Destination Call Id=2398, Source Call Id=2396,

Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=ON(0x2),

Modem=ON(0x2), Codec Bytes=160, Signal Type=2, Seq Num Start=3778)

004289: Jul 25 14:55:30.284: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_cap_ind_ack: src_call_id = 2398, dst_call_id = 2396, is_cap_ack = 1

004290: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_stop_service:

G711_U (5)

004291: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_close_service:

G711_U (5)

004292: Jul 25 14:55:30.284: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_close_service:

NONE (0)

004293: Jul 25 14:55:30.284: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_init:

004294: Jul 25 14:55:30.284: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_open_service:

G711_U (5)

004295: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_init:

004296: Jul 25 14:55:30.284: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_open_service:

G711_U (5)

004297: Jul 25 14:55:30.284: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_voice_config_params:

004298: Jul 25 14:55:30.284: 7 parameters

[0] ENCAP RTP: Tx SSRC=768, Rx SSRC=0, Tx VPXCC=128, Rx VPXCC=0

IFP payload type=122, SID support=19, TSE payload=101, Sequence number start=3984

Redundancy=0, ClearChannel payload type=125, Fax payload type=122

Alaw PCM switchover=8, MuLAW PCM switchover=0

DTMF payload type=121, NTE receive payload type=0

LMR tone payload type=0, NTE tone payload type=0

Dynamic payload=0, Codec=g711ulaw

[1] PO_JITTER: mode=2 initial=60(ms) max=1000(ms) min=40(ms) fax_nom=300(ms)

[2] INFO_FIELD_SIZE: 160 bytes

[3] MR_PROTOCOL: 0 bytes

[4] IDLE_CODE_DET: Enable=0, Code=0x0, Duration=6000(ms)

[5] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[6] CNG: 1

004299: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_config_params:

3 parameters

004300: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_start_service:

G711_U (5)

004301: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_call_progress_detect:

004302: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_voice_config_params:

004303: Jul 25 14:55:30.288: 5 parameters

[0] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[1] GAIN: Input=0.0(dB), Output=0.0(dB)

[2] AGC CONTROL: Input=off, Output=off

[3] DIGIT_RELAY: 2

[4] VAD: Enable=1, Threshold=-38(dBm)

004304: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmp_dsmapi_connect_cb: .

004305: Jul 25 14:55:30.288: //2398/4FE2E67381E1/CCAPI/cc_api_voice_mode_event:

Call Id=2398

004306: Jul 25 14:55:30.288: //2398/4FE2E67381E1/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x899DD5D4)

004307: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmp_dsmapi_connect_cb: dmgr->current_feature = 1, dmgr->previous_feature = 0

004308: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_call_progress_detect:

004309: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_inband_det_ctrl:

SignalType=1, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004310: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_inband_det_ctrl:

SignalType=4, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004311: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_inband_det_ctrl:

SignalType=5, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004312: Jul 25 14:55:30.288: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_inband_det_ctrl:

SignalType=9, Mode=1, Signal suppression bitmap 1, signal suppression mode 1

004313: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

004314: Jul 25 14:55:30.288: 7 parameters

[0] ENCAP RTP: Tx SSRC=770, Rx SSRC=0, Tx VPXCC=128, Rx VPXCC=0

IFP payload type=122, SID support=19, TSE payload=101, Sequence number start=3778

Redundancy=0, ClearChannel payload type=125, Fax payload type=122

Alaw PCM switchover=8, MuLAW PCM switchover=0

DTMF payload type=121, NTE receive payload type=0

LMR tone payload type=0, NTE tone payload type=0

Dynamic payload=0, Codec=g711ulaw

[1] PO_JITTER: mode=2 initial=60(ms) max=1000(ms) min=40(ms) fax_nom=300(ms)

[2] INFO_FIELD_SIZE: 160 bytes

[3] MR_PROTOCOL: 0 bytes

[4] IDLE_CODE_DET: Enable=0, Code=0x0, Duration=6000(ms)

[5] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[6] CNG: 1

004315: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_config_params:

3 parameters

004316: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_start_service:

G711_U (5)

004317: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_call_progress_detect:

004318: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

004319: Jul 25 14:55:30.288: 5 parameters

[0] ECHO_CANCEL: Flags=0x37, Echo length=128(ms)

[1] GAIN: Input=0.0(dB), Output=0.0(dB)

[2] AGC CONTROL: Input=off, Output=off

[3] DIGIT_RELAY: 2

[4] VAD: Enable=1, Threshold=-38(dBm)

004320: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsmp_dsmapi_connect_cb: .

004321: Jul 25 14:55:30.288: //2396/4FE2E67381E1/CCAPI/cc_api_voice_mode_event:

Call Id=2396

004322: Jul 25 14:55:30.288: //2396/4FE2E67381E1/CCAPI/cc_api_voice_mode_event:

Call Entry(Context=0x899DFF54)

004323: Jul 25 14:55:30.288: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsmp_dsmapi_connect_cb: dmgr->current_feature = 1, dmgr->previous_feature = 0

004324: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

004325: Jul 25 14:55:30.292: 1 parameter

[0] PAK_SUPPRESS: 0

004326: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_call_progress_detect:

004327: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_det_ctrl:

SignalType=1, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004328: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_det_ctrl:

SignalType=4, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004329: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_det_ctrl:

SignalType=5, Mode=1, Signal suppression bitmap 0, signal suppression mode 0

004330: Jul 25 14:55:30.292: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_inband_det_ctrl:

SignalType=9, Mode=1, Signal suppression bitmap 1, signal suppression mode 1

004331: Jul 25 14:55:30.292: //2396/4FE2E67381E1/CCAPI/cc_process_notify_bridge_done:

Conference Id=0x2CE, Call Id1=2396, Call Id2=2398

004332: Jul 25 14:55:30.308: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=4

004333: Jul 25 14:55:30.308: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=4 nr=2

004334: Jul 25 14:55:30.308: ISDN BR0/1/0 Q931: DISCONNECT pd = 8 callref = 0xE2

Cause i = 0x809C - Invalid number format (incomplete number)

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Progress Ind i = 0x8288 - In-band info or appropriate now available

004335: Jul 25 14:55:30.336: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=5

004336: Jul 25 14:55:30.364: ISDN BR0/1/0 Q921: User RX <- INFO sapi=0 tei=75, ns=2 nr=5

004337: Jul 25 14:55:30.364: ISDN BR0/1/0 Q931: FACILITY pd = 8 callref = 0x62

Facility i = 0x91A40702020080810101

Protocol Profile = Remote Operations Protocol

0xA40702020080810101

Component = Reject component

Invoke Id = 128

Problem = Invoke problem; Unrecognized operation

004338: Jul 25 14:55:30.364: ISDN BR0/1/0 Q921: User TX -> RR sapi=0 tei=75 nr=3

004339: Jul 25 14:55:30.364: ISDN BR0/1/0 **ERROR**: Ux_BadMsg: Invalid Message for call state 11, call id 0x1FD, call ref 0xE2, event 0x62

004340: Jul 25 14:55:30.364: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=5 nr=3

004341: Jul 25 14:55:30.364: ISDN BR0/1/0 Q931: STATUS pd = 8 callref = 0xE2

Cause i = 0x80E262 - Message not compatible with call state or not implemented

Call State i = 0x0B

004342: Jul 25 14:55:30.388: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=6

004343: Jul 25 14:55:30.404: ISDN BR0/1/0 Q921: User RX <- INFO sapi=0 tei=75, ns=3 nr=6

004344: Jul 25 14:55:30.404: ISDN BR0/1/0 Q931: RELEASE pd = 8 callref = 0x62

Cause i = 0x82E31E - Information element not implemented

004345: Jul 25 14:55:30.404: ISDN BR0/1/0 Q921: User TX -> RR sapi=0 tei=75 nr=4

004346: Jul 25 14:55:30.408: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=6 nr=4

004347: Jul 25 14:55:30.408: ISDN BR0/1/0 Q931: RELEASE_COMP pd = 8 callref = 0xE2

004348: Jul 25 14:55:30.408: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_cc_call_disconnected:

Cause Value=99

004349: Jul 25 14:55:30.408: //2396/4FE2E67381E1/CCAPI/cc_api_call_disconnected:

Cause Value=99, Interface=0x8869A614, Call Id=2396

004350: Jul 25 14:55:30.408: //2396/4FE2E67381E1/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=28, Retry Count=0)

004351: Jul 25 14:55:30.408: //2396/4FE2E67381E1/CCAPI/ccConferenceDestroy:

Conference Id=0x2CE, Tag=0x0

004352: Jul 25 14:55:30.412: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_bridge_drop:

004353: Jul 25 14:55:30.412: dsp_stream_mgr_bridge_drop, src_call_id 2396, dst_call_id 2398

004354: Jul 25 14:55:30.412: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_delete_conn_array_entry: dmgr->connArr.count = 0

004355: Jul 25 14:55:30.412: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_disconnect_and_delete_video_streams: .

004356: Jul 25 14:55:30.412: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_disconnect_and_delete_video_streams: stm_id: 0, call_id: 0

004357: Jul 25 14:55:30.412: //2396/4FE2E67381E1/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2396,

Destination Call Id=2398, Disposition=0x0, Tag=0x0

004358: Jul 25 14:55:30.412: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_bridge_drop:

004359: Jul 25 14:55:30.412: dsp_stream_mgr_bridge_drop, src_call_id 2398, dst_call_id 2396

004360: Jul 25 14:55:30.412: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_delete_conn_array_entry: dmgr->connArr.count = 0

004361: Jul 25 14:55:30.412: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_disconnect_and_delete_video_streams: .

004362: Jul 25 14:55:30.412: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_disconnect_and_delete_video_streams: stm_id: 0, call_id: 0

004363: Jul 25 14:55:30.412: //2398/4FE2E67381E1/CCAPI/cc_api_bridge_drop_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2398,

Destination Call Id=2396, Disposition=0x0, Tag=0x0

004364: Jul 25 14:55:30.412: //2396/4FE2E67381E1/CCAPI/cc_generic_bridge_done:

Conference Id=0x2CE, Source Interface=0x8869A614, Source Call Id=2398,

Destination Call Id=2396, Disposition=0x0, Tag=0x0

004365: Jul 25 14:55:30.412: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_voice_config_params:

004366: Jul 25 14:55:30.412: 1 parameter

[0] PAK_SUPPRESS: 1

004367: Jul 25 14:55:30.412: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_voice_config_params:

004368: Jul 25 14:55:30.412: 1 parameter

[0] PAK_SUPPRESS: 1

004369: Jul 25 14:55:30.416: //2398/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=28)

004370: Jul 25 14:55:30.416: //2398/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)

004371: Jul 25 14:55:30.416: //2398/4FE2E67381E1/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

004372: Jul 25 14:55:30.416: //2396/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=28, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=28)

004373: Jul 25 14:55:30.416: //2396/4FE2E67381E1/CCAPI/ccCallDisconnect:

Cause Value=28, Call Entry(Responsed=TRUE, Cause Value=28)

004374: Jul 25 14:55:30.416: //2396/4FE2E67381E1/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

004375: Jul 25 14:55:30.416: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_set_feature_parameters: Fax Relay Mode = 4, Primary Fax Protocol = 1

Fax fallback protocol = 4, T.38 Fax Version 0

004376: Jul 25 14:55:30.416: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_initiate_close: .

004377: Jul 25 14:55:30.420: //2398/4FE2E67381E1/CCAPI/cc_api_call_disc_cause_update:

Cause Value=28

004378: Jul 25 14:55:30.420: //2398/4FE2E67381E1/CCAPI/cc_api_call_disc_cause_update:

Call Entry(Disconnect Cause=28)

004379: Jul 25 14:55:30.420: //2398/51B70A649BE7/VTSP:(0/1/0):-1:1:3/vtsp_do_call_history:

Coder Rate=5

004380: Jul 25 14:55:30.420: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsp_stream_mgr_destroy: .

004381: Jul 25 14:55:30.420: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsm_delete_all_streams: .

004382: Jul 25 14:55:30.420: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmapi_accept_modem_passthrough_session: : dmgr: 86698CE0, active sessions 0, max sessions: 16 rejected sessions till now: 0

004383: Jul 25 14:55:30.420: //2396/4FE2E67381E1/CCAPI/cc_api_call_disc_cause_update:

Cause Value=28

004384: Jul 25 14:55:30.420: //2396/4FE2E67381E1/CCAPI/cc_api_call_disc_cause_update:

Call Entry(Disconnect Cause=28)

004385: Jul 25 14:55:30.420: //2396/4FE2E67381E1/VTSP:(0/1/0):-1:1:2/vtsp_do_call_history:

Coder Rate=5

004386: Jul 25 14:55:30.420: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsp_stream_mgr_destroy: .

004387: Jul 25 14:55:30.420: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsm_delete_all_streams: .

004388: Jul 25 14:55:30.424: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsmapi_accept_modem_passthrough_session: : dmgr: 8AD9F7CC, active sessions 0, max sessions: 16 rejected sessions till now: 0

004389: Jul 25 14:55:30.424: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_stop_service:

G711_U (5)

004390: Jul 25 14:55:30.424: //2398/51B70A649BE7/DSPAPI/[0/1:3]/dsp_close_service:

G711_U (5)

004391: Jul 25 14:55:30.424: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_stop_service:

G711_U (5)

004392: Jul 25 14:55:30.424: //2396/4FE2E67381E1/DSPAPI/[0/1:2]/dsp_close_service:

G711_U (5)

004393: Jul 25 14:55:30.424: //2398/51B70A649BE7/DSM:(0/1/0):-1/dsmp_dsmapi_reserve_resource_cb: .

004394: Jul 25 14:55:30.424: //2396/4FE2E67381E1/DSM:(0/1/0):-1/dsmp_dsmapi_reserve_resource_cb: .

004395: Jul 25 14:55:30.424: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=7

004396: Jul 25 14:55:30.424: //2398/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x8869A614, Tag=0x0, Call Id=2398,

Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)

004397: Jul 25 14:55:30.424: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x89AE5E5C; count=1

004398: Jul 25 14:55:30.428: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x89410D90

004399: Jul 25 14:55:30.428: //2398/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

tg for this call is ALL_BRI success/fail is 0

004400: Jul 25 14:55:30.428: //2398/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

004401: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004402: Jul 25 14:55:30.428: :cc_free_feature_vsa freeing 899C7AA8

004403: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004404: Jul 25 14:55:30.428: vsacount in free is 5

004405: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004406: Jul 25 14:55:30.428: vsacount in free is 4

004407: Jul 25 14:55:30.428: //2396/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x8869A614, Tag=0x0, Call Id=2396,

Call Entry(Disconnect Cause=28, Voice Class Cause Code=0, Retry Count=0)

004408: Jul 25 14:55:30.428: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_RegXruleNumInfo: stack=0x89AE443C; count=2

004409: Jul 25 14:55:30.428: //-1/4FE2E67381E1/RXRULE/regxrule_stack_pop_callinfo_internal: numinfo=0x89412950

004410: Jul 25 14:55:30.428: //2396/4FE2E67381E1/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

004411: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004412: Jul 25 14:55:30.428: :cc_free_feature_vsa freeing 899C7FE8

004413: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004414: Jul 25 14:55:30.428: vsacount in free is 3

004415: Jul 25 14:55:30.428: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

004416: Jul 25 14:55:30.428: vsacount in free is 2

004417: Jul 25 14:55:30.432: ISDN BR0/1/0 Q921: User TX -> INFO sapi=0 tei=75, ns=7 nr=4

004418: Jul 25 14:55:30.432: ISDN BR0/1/0 Q931: RELEASE pd = 8 callref = 0x42

Cause i = 0x82E31E - Information element not implemented

004419: Jul 25 14:55:30.448: ISDN BR0/1/0 Q921: User RX <- RR sapi=0 tei=75 nr=8

004420: Jul 25 14:55:30.488: ISDN BR0/1/0 Q921: User RX <- INFO sapi=0 tei=75, ns=4 nr=8

004421: Jul 25 14:55:30.488: ISDN BR0/1/0 Q931: RELEASE_COMP pd = 8 callref = 0xC2

004422: Jul 25 14:55:30.488: ISDN BR0/1/0 Q921: User TX -> RR sapi=0 tei=75 nr=5

004423: Jul 25 14:55:39.664: //1/D57121248002/DSM:(50/0/9):-1/dsp_stream_mgr_get_levels_sync: .

004424: Jul 25 14:55:39.664: //1/D57121248002/DSPAPI/[]/dsp_query_info:

Request ID=6, Reset Flag=FALSE Q:TX RX Levels

004425: Jul 25 14:55:39.664: //1/D57121248002/DSPAPI/[]/dsp_tx_stats:

Request ID=6, Packets: Voice=0, Signaling=0, ComfortNoise=0

TX duration=0(ms): Voice=0(ms), FAX=0(ms)

004426: Jul 25 14:55:39.664: //1/D57121248002/DSPAPI/[]/dsp_rx_stats:

Request ID=6, Packets: Voice=0, Signalling=0, ComfortNoise=0

RX duration=0(ms): Voice=0(ms), FAX=0(ms)

Packets: Bad Sequence=0, Bad Protocol=0, Late=0, Early=0

004427: Jul 25 14:55:39.664: //1/D57121248002/DSPAPI/[]/dsp_level_stats:

Request ID=6, TX: power=0.0(dBm0), mean=0.0(linear PCM), RX: power=0.0(dBm0), mean=0.0(linear PCM)

Background Noise=0.0(dBm0), ERL level=0.0(dB)

ACOM level=0.0(dB), Current TX act=2, Current RX act=2

Reflector locations: 0(ms) 32768(ms) 45977(ms), MAX reflector=37830(ms)

2 ECAN control words: 554B 0, ECAN Version=1F3A.FFFF(na)

004428: Jul 25 14:55:39.664: //1/D57121248002/DSM:(50/0/9):-1/dsmp_dsmapi_stats_cb: .

004429: Jul 25 14:55:39.664: //2/D57121248002/DSM:(0/4/0):-1/dsp_stream_mgr_get_levels_sync: .

004430: Jul 25 14:55:39.664: //2/D57121248002/DSPAPI/[0/1:1]/dsp_query_info:

Request ID=6, Reset Flag=FALSE Q:TX RX Levels

004431: Jul 25 14:55:39.672: //2/D57121248002/DSPAPI/[0/1:1]/dsp_tx_stats:

Request ID=6, Packets: Voice=121541417, Signaling=0, ComfortNoise=0

TX duration=-1864138946(ms): Voice=-1864138946(ms), FAX=0(ms)

004432: Jul 25 14:55:39.672: //2/D57121248002/DSPAPI/[0/1:1]/dsp_rx_stats:

Request ID=6, Packets: Voice=0, Signalling=0, ComfortNoise=0

RX duration=-1864138946(ms): Voice=0(ms), FAX=0(ms)

Packets: Bad Sequence=0, Bad Protocol=0, Late=0, Early=0

004433: Jul 25 14:55:39.672: //2/D57121248002/DSPAPI/[0/1:1]/dsp_level_stats:

Request ID=6, TX: power=-64.0(dBm0), mean=-79.3(linear PCM), RX: power=-68.5(dBm0), mean=-55.3(linear PCM)

Background Noise=-81.0(dBm0), ERL level=6.0(dB)

ACOM level=6.0(dB), Current TX act=1, Current RX act=1

Reflector locations: 4(ms) 0(ms) 0(ms), MAX reflector=4(ms)

2 ECAN control words: 7C 1000, ECAN Version=9183.890

004434: Jul 25 14:55:39.672: //2/D57121248002/DSM:(0/4/0):-1/dsmp_dsmapi_stats_cb: .

004435: Jul 25 14:55:40.488: ISDN BR0/1/0 Q921: User TX -> RRp sapi=0 tei=75 nr=5

004436: Jul 25 14:55:40.500: ISDN BR0/1/0 Q921: User RX <- RRf sapi=0 tei=75 nr=8

004437: Jul 25 14:55:45.436: ISDN BR0/1/0 Q921: User RX <- DISCp sapi=0 tei=75

004438: Jul 25 14:55:45.440: ISDN BR0/1/0 Q921: User TX -> UAf sapi=0 tei=75

004439: Jul 25 14:55:45.440: ISDN BR0/1/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

-----------------------------------------------------------------------------END-----------------------------------------------------------------------------

Is it possible, that the incoming number can't be replaced with the customer number by forwarding the call and the pstn is blocking the call?

please help.

best regards

claude

paolo bevilacqua · ‎07-25-2013

Only post the debug mentioned and nothing more.

unpersonal · ‎07-30-2013

Hallo Paolo

Thanks for your answer. Is that OK?

Best Regards

Claude

*******NORMAL INCOMING CALL*******

005592: ISDN BR0/1/0 Q931: RX <- SETUP pd = 8 callref = 0x69

Sending Complete

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x89

Exclusive, B1

Calling Party Number i = 0x0181, '0417256565'

Plan:ISDN, Type:Unknown

Called Party Number i = 0xC1, '8504452'

Plan:ISDN, Type:Subscriber(local)

High Layer Compat i = 0x9181

005593: ISDN BR0/1/0 Q931: TX -> CALL_PROC pd = 8 callref = 0xE9

Channel ID i = 0x89

Exclusive, B1

005594: ISDN BR0/1/0 Q931: TX -> ALERTING pd = 8 callref = 0xE9

Progress Ind i = 0x8188 - In-band info or appropriate now available

005595: ISDN BR0/1/0 Q931: TX -> CONNECT pd = 8 callref = 0xE9

Channel ID i = 0x89

Exclusive, B1

005596: ISDN BR0/1/0 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x69

005597: Jul 30 07:43:10.107: %ISDN-6-CONNECT: Interface BRI0/1/0:1 is now connected to 21 Hauptapparat Alpina

005598: Jul 30 07:43:29.955: %ISDN-6-DISCONNECT: Interface BRI0/1/0:1 disconnected from 0417256565 , call lasted 19 seconds

005599: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8 callref = 0xE9

Cause i = 0x8090 - Normal call clearing

005600: ISDN BR0/1/0 Q931: RX <- RELEASE pd = 8 callref = 0x69

005601: ISDN BR0/1/0 Q931: TX -> RELEASE_COMP pd = 8 callref = 0xE9

005602: ISDN BR0/1/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

005603: ISDN BR0/1/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 8502086

005604: ISDN BR0/1/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Called num 0417256565

005605: ISDN BR0/1/0 Q931: Sending SETUP callref = 0x0073 callID = 0x81BA switch = basic-net3 interface = User

*******NORMAL OUTGOING CALL*******

005606: ISDN BR0/1/0 Q931: TX -> SETUP pd = 8 callref = 0x73

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x89

Exclusive, B1

Progress Ind i = 0x8183 - Origination address is non-ISDN

Calling Party Number i = 0x0080, '8502086'

Plan:Unknown, Type:Unknown

Called Party Number i = 0x80, '0417256565'

Plan:Unknown, Type:Unknown

Sending Complete

005607: ISDN BR0/1/0 Q931: RX <- CALL_PROC pd = 8 callref = 0xF3

Channel ID i = 0x89

Exclusive, B1

005608: ISDN BR0/1/0 Q931: RX <- CONNECT pd = 8 callref = 0xF3

Date/Time i = 0x0D071E0930

Date (dd-mm-yr) = 13-07-30

Time (hr:mnt:sec) = 09:48:76

Connected Number i = 0x0183, '0417256565'

005609: Jul 30 07:44:36.123: %ISDN-6-CONNECT: Interface BRI0/1/0:1 is now connected to 21 Hauptapparat Alpina

005610: ISDN BR0/1/0 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x73

005611: ISDN BR0/1/0 Q931: RX <- FACILITY pd = 8 callref = 0xF3

Facility i = 0x91A11B020200800201213012A10D810346522EA20681010A820101820100

Protocol Profile = Remote Operations Protocol

0xA11B020200800201213012A10D810346522EA20681010A820101820100

Component = Invoke component

Invoke Id = 128

Operation = AOCDCurrency

Currency = FR.

Currency amount = 10, Multiplier = 1/100

Type of charging : Subtotal

005612: ISDN BR0/1/0 Q931: RX <- INFORMATION pd = 8 callref = 0xF3

Display i = 'FR. 0.10'

005613: Jul 30 07:44:44.711: %SYS-5-CONFIG_I: Configured from http by sysadminacc on 192.168.30.2

005614: Jul 30 07:44:53.775: %SYS-5-CONFIG_I: Configured from http by sysadminacc on 192.168.30.2

005615: Jul 30 07:45:06.039: %ISDN-6-DISCONNECT: Interface BRI0/1/0:1 disconnected from 0417256565 , call lasted 29 seconds

005616: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8 callref = 0x73

Cause i = 0x8090 - Normal call clearing

005617: ISDN BR0/1/0 Q931: RX <- RELEASE pd = 8 callref = 0xF3

Facility i = 0x91A11A020200810201233011300FA10D810346522EA20681010A820101

Protocol Profile = Remote Operations Protocol

0xA11A020200810201233011300FA10D810346522EA20681010A820101

Component = Invoke component

Invoke Id = 129

Operation = AOCECurrency

Currency = FR.

Currency amount = 10, Multiplier = 1/100

AOC-E Charging association Charge Identifier 0

Display i = 'FR. 0.10'

005618: ISDN BR0/1/0 Q931: TX -> RELEASE_COMP pd = 8 callref = 0x73

005619: ISDN BR0/1/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

*******CHANGE CONFIGURATION ON UC540 TO REDIRECT CALLS TO EXTERNAL MOBILE PHONE*******

005620: Jul 30 07:46:00.235: %SYS-5-CONFIG_I: Configured from console by sysadminacc on vty1 (192.168.30.2)

005621: Jul 30 07:47:08.419: %SYS-5-CONFIG_I: Configured from console by sysadminacc on vty1 (192.168.30.2)

005622: Jul 30 07:47:11.999: %SYS-5-CONFIG_I: Configured from http by sysadminacc on 192.168.30.2

*******INCOMING CALL THAT SHOULD BE REDIRECTED TO MOBILE PHONE --> FAILED*******

005623: ISDN BR0/1/0 Q931: RX <- SETUP pd = 8 callref = 0x68

Sending Complete

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x89

Exclusive, B1

Calling Party Number i = 0x0181, '0417256565'

Plan:ISDN, Type:Unknown

Called Party Number i = 0xC1, '8504452'

Plan:ISDN, Type:Subscriber(local)

High Layer Compat i = 0x9181

005624: ISDN BR0/1/0 Q931: TX -> CALL_PROC pd = 8 callref = 0xE8

Channel ID i = 0x89

Exclusive, B1

005625: ISDN BR0/1/0 Q931: TX -> ALERTING pd = 8 callref = 0xE8

Progress Ind i = 0x8188 - In-band info or appropriate now available

005626: ISDN BR0/1/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x1, Calling num 8502086

005627: ISDN BR0/1/0 Q931: Sending SETUP callref = 0x0074 callID = 0x81BB switch = basic-net3 interface = User

005628: ISDN BR0/1/0 Q931: TX -> SETUP pd = 8 callref = 0x74

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0x8A

Exclusive, B2

Calling Party Number i = 0x0181, '8502086'

Plan:ISDN, Type:Unknown

Called Party Number i = 0xC1, '0785554044'

Plan:ISDN, Type:Subscriber(local)

High Layer Compat i = 0x9181

Sending Complete

005629: ISDN BR0/1/0 Q931: RX <- CALL_PROC pd = 8 callref = 0xF4

Channel ID i = 0x8A

Exclusive, B2

005630: ISDN BR0/1/0 Q931: RX <- DISCONNECT pd = 8 callref = 0xF4

Cause i = 0x829C - Invalid number format (incomplete number)

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Progress Ind i = 0x8288 - In-band info or appropriate now available

005631: ISDN BR0/1/0 **ERROR**: isdnAsn1Inv: error: op 35

005632: ISDN BR0/1/0 **ERROR**: isdnAsn1Component: ERROR

005633: ISDN BR0/1/0 **ERROR**: process_facility_ie: Decode of facility IE operation 35 failed1

005634: ISDN BR0/1/0 **ERROR**: isdnAsn1Inv: error: op 35

005635: ISDN BR0/1/0 **ERROR**: isdnAsn1Component: ERROR

005636: ISDN BR0/1/0 **ERROR**: process_facility_ie: Decode of facility IE operation 35 failed1

005637: ISDN BR0/1/0 Q931: TX -> FACILITY pd = 8 callref = 0xE8

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Facility i = 0x9E810003677464000000164641432C0D0A50524E2C6973646E2A2C2C2C0D0A0D0A

- Unknown Service Discriminator 158

005638: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8 callref = 0xE8

Cause i = 0x809C - Invalid number format (incomplete number)

Facility i = 0x91A109020200800201230500

Protocol Profile = Remote Operations Protocol

0xA109020200800201230500

Component = Invoke component, Unsupported operation

Progress Ind i = 0x8288 - In-band info or appropriate now available

005639: ISDN BR0/1/0 Q931: RX <- FACILITY pd = 8 callref = 0x68

Facility i = 0x91A40702020080810101

Protocol Profile = Remote Operations Protocol

0xA40702020080810101

Component = Reject component

Invoke Id = 128

Problem = Invoke problem; Unrecognized operation

005640: ISDN BR0/1/0 **ERROR**: Ux_BadMsg: Invalid Message for call state 11, call id 0x247, call ref 0xE8, event 0x62

005641: ISDN BR0/1/0 Q931: TX -> STATUS pd = 8 callref = 0xE8

Cause i = 0x80E262 - Message not compatible with call state or not implemented

Call State i = 0x0B

005642: ISDN BR0/1/0 Q931: RX <- RELEASE pd = 8 callref = 0x68

Cause i = 0x82E31E - Information element not implemented

005643: ISDN BR0/1/0 Q931: TX -> RELEASE_COMP pd = 8 callref = 0xE8

005644: ISDN BR0/1/0 Q931: TX -> RELEASE pd = 8 callref = 0x74

Cause i = 0x82E31E - Information element not implemented

005645: ISDN BR0/1/0 Q931: RX <- RELEASE_COMP pd = 8 callref = 0xF4

005646: ISDN BR0/1/0 Q931: Ux_DLRelInd: DL_REL_IND received from L2

*******END*******

paolo bevilacqua · ‎07-30-2013

try

interface bri01/0

isdn map address . plan unknown type unknown