voicemail don't take a call by noan-option

[icoskonstanz]

Hello,

i've configured an UC560 with voicemail on number '39'. The voicemail ist reachable and configureable by browser.

You can also call direct '39' with an IPPhone and get the Voicemail to configure it.

Now i have configured the ephone-dn with the following call-forward :

call-forward noan 39 timeout 20

An incoming call will ring the 20 seconds, but then the system hangs up the call.

Where can i find the failure ?

In which debugging can i see, if the call-forwarding works correct ?

Please help.

Greets

H. Wieser

[paolo bevilacqua]

You can start with e.g "debug ccsip message" and "term mon".

[icoskonstanz]

Hello,

sorry there is no output when debug ccsip is active.

I activated debug isdn standard an get the following result :

000934: Jan  4 13:29:59.396: ISDN BR0/1/0 Q931: TX -> DISCONNECT pd = 8  callref = 0xA7

        Cause i = 0x8081 - Unallocated/unassigned number

000935: Jan  4 13:29:59.600: ISDN BR0/1/0 Q931: RX <- RELEASE pd = 8  callref = 0x27

        Cause i = 0x8081 - Unallocated/unassigned number

000936: Jan  4 13:29:59.600: ISDN  EVENTd: cc_clear_free_list freeing 0x8716D1E8

000937: Jan  4 13:29:59.600: ISDN BR0/1/0 EVENT: process_rxstate: ces/callid 1/0x1C calltype 2 HOST_DISCONNECT_ACK

Can you help me with this ?

Greets

[johschaf]

Hello Bernard,

You should see some SIP invites and responses when dialing into voicemail. Can you verify you had the following debug running: debug ccsip messages? If you weren't running that debug, please recreate the issue with that debug running and post the output here. I'd also like to see if you can access the voicemail web interface to verify the operational status of CUE. If the UC isn't your default gateway, for testing purposes can you change your default gateway to the UC, and then try accessing voicemail web interface?

Thanks,

-john

[icoskonstanz]

Hello John,

sorry, but i'm sure i'd activated the debug. Here my inputs :

>>debug ccsip messages

>>CCSIP SPI: SIP Call Message tracing is enabled  (filter is OFF)

>>terminal monitor

The webinterface is reachable and useable ! Without any Problems

Thanks for an answer !

Greets

[johschaf]

Hello Bernhard,

Can you post the voicemail dial-peer and service module configuration here? To find the voicemail dial peer search for: "destination-pattern xxx"(with no quotes, and where xxx is your voicemail number). The voicemail module configuration should be under: interface Integrated-Service-Engine0/0

Thanks,

-john

[icoskonstanz]

Hello John,

here the configurations :

dial-peer voice 2000 voip
description ** cue voicemail pilot number **
translation-profile outgoing XFER_TO_VM_PROFILE
destination-pattern 39
b2bua
session protocol sipv2
session target ipv4:10.1.10.1
voice-class sip outbound-proxy ipv4:10.1.10.1
dtmf-relay sip-notify
codec g711ulaw
no vad

interface Integrated-Service-Engine0/0
description Interface used to manage integrated application modulecue is initialized with default IMAP group
ip unnumbered Vlan90
ip nat inside
ip virtual-reassembly in
service-module ip address 10.1.10.1 255.255.255.252
service-module ip default-gateway 10.1.10.2

Thanks for your help.

[johschaf]

Hello Bernhard,

The configuration appears correct. Can you verify you have the following under telephony-service:

telephony-service

voicemail 39

If you have that configuration in telephony service, can you run another debug with the following debug running: debug voice ccapi inout. I'd like to make sure we are hitting the voicemail dial-peer, which then should generate the sip messages we are looking for.

Thanks,

-john

[icoskonstanz]

Hello John,

here the debug of voice ccapi inout :

000986: Jan  8 08:18:09.334: //-1/C55712E98029/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=01736601189
   cisco-anitype=4
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=0
   dest=590926
   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

000987: Jan  8 08:18:09.334: //-1/C55712E98029/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x88694EF4, Call Info(
   Calling Number=01736601189,(Calling Name=)(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed),
   Called Number=590926(TON=Subscriber, NPI=ISDN),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,
   Incoming Dial-peer=50, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
000988: Jan  8 08:18:09.334: //-1/C55712E98029/CCAPI/ccCheckClipClir:
   In: Calling Number=01736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed)
000989: Jan  8 08:18:09.334: //-1/C55712E98029/CCAPI/ccCheckClipClir:
   Out: Calling Number=01736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed)
000990: Jan  8 08:18:09.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000991: Jan  8 08:18:09.334: :cc_get_feature_vsa malloc success
000992: Jan  8 08:18:09.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000993: Jan  8 08:18:09.334:  cc_get_feature_vsa count is 3
000994: Jan  8 08:18:09.334: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

000995: Jan  8 08:18:09.334: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327056208,feature_id:237
000996: Jan  8 08:18:09.334: //146/C55712E98029/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=01736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed),
   Called Number=590926(TON=Subscriber, NPI=ISDN))
000997: Jan  8 08:18:09.334: //146/C55712E98029/CCAPI/cc_process_call_setup_ind:
   Event=0x886289A0
000998: Jan  8 08:18:09.334: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Try with the demoted called number 26
000999: Jan  8 08:18:09.334: //146/C55712E98029/CCAPI/ccCallSetContext:
   Context=0x8AB6AB08
001000: Jan  8 08:18:09.334: //146/C55712E98029/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 146 with tag 50 to app "_ManagedAppProcess_Default"
001001: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
001002: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=20012, Params=0x8AB5C718, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
001003: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCheckClipClir:
   In: Calling Number=001736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed)
001004: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCheckClipClir:
   Out: Calling Number=001736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed)
001005: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCallSetupRequest:
   Destination Pattern=590926$, Called Number=590926, Digit Strip=TRUE
001006: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccCallSetupRequest:
   Calling Number=001736601189(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed),
   Called Number=590926(TON=Subscriber, NPI=ISDN),
   Redirect Number=, Display Info=
   Account Number=, Final Destination Flag=TRUE,
   Guid=C55712E9-58A2-11E2-8029-442B03D578D1, Outgoing Dial-peer=20012
001007: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=001736601189
   cisco-anitype=4
   cisco-aniplan=1
   cisco-anipi=0
   cisco-anisi=0
   dest=590926
   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

001008: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x89720B14, Interface Type=6, Destination=, Mode=0x0,
   Call Params(Calling Number=001736601189,(Calling Name=)(TON=Subscriber, NPI=ISDN, Screening=Not Screened, Presentation=Allowed),
   Called Number=590926(TON=Subscriber, NPI=ISDN), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=20012, Call Count On=FALSE,
   Source Trkgrp Route Label=ALL_BRI, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=)
001009: Jan  8 08:18:09.338: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001010: Jan  8 08:18:09.338: :cc_get_feature_vsa malloc success
001011: Jan  8 08:18:09.338: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001012: Jan  8 08:18:09.338:  cc_get_feature_vsa count is 4
001013: Jan  8 08:18:09.338: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001014: Jan  8 08:18:09.338: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055312,feature_id:238
001015: Jan  8 08:18:09.338: //147/C55712E98029/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
001016: Jan  8 08:18:09.338: //147/C55712E98029/CCAPI/ccCallSetContext:
   Context=0x8AB5C6C8
001017: Jan  8 08:18:09.338: //146/C55712E98029/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=20012
001018: Jan  8 08:18:09.346: //147/C55712E98029/CCAPI/cc_api_update_call_info:
   Interface=0x89720B14, Call Id=0x93
001019: Jan  8 08:18:09.346: //147/C55712E98029/CCAPI/cc_api_call_proceeding:
   Interface=0x89720B14, Progress Indication=NULL(0)
001020: Jan  8 08:18:09.350: //147/C55712E98029/CCAPI/cc_api_call_alert:
   Interface=0x89720B14, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
001021: Jan  8 08:18:09.350: //147/C55712E98029/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
001022: Jan  8 08:18:09.350: //146/C55712E98029/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
001023: Jan  8 08:18:09.350: //146/C55712E98029/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
001024: Jan  8 08:18:09.350: //147/C55712E98029/CCAPI/ccCallFeature:
   Feature Type=25, Call Id=147
001025: Jan  8 08:18:19.350: //147/C55712E98029/CCAPI/cc_api_call_disconnected:
   Cause Value=19, Interface=0x89720B14, Call Id=147
001026: Jan  8 08:18:19.350: //147/C55712E98029/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=19, Retry Count=0)
001027: Jan  8 08:18:19.354: //146/C55712E98029/CCAPI/ccCallReleaseResources:
   release reserved xcoding resource.
001028: Jan  8 08:18:19.354: //147/C55712E98029/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
001029: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
   type=6 redirecting_number=590926
001030: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectNumber:
   type=6  Original Called Number=590926, Called Number=590926, Calling Number=001736601189, Calling DN=-1 Calling Id=146,
   Redirect Number=39, Redirect Reason=2
001031: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/ccUpdateRedirectingNumber:
   type=6 redirecting_number=590926
001032: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001033: Jan  8 08:18:19.354: :cc_get_feature_vsa malloc success
001034: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001035: Jan  8 08:18:19.354:  cc_get_feature_vsa count is 5
001036: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001037: Jan  8 08:18:19.354: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055760,feature_id:239
001038: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001039: Jan  8 08:18:19.354:  feature_vsa is 8AB40F48,featname is 0
001040: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001041: Jan  8 08:18:19.354: :cc_get_feature_vsa malloc success
001042: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001043: Jan  8 08:18:19.354:  cc_get_feature_vsa count is 6
001044: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001045: Jan  8 08:18:19.354: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055536,feature_id:240
001046: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001047: Jan  8 08:18:19.354: : Generating new featurevsa,inserting it
001048: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001049: Jan  8 08:18:19.354:  feature call forward featname is 3
001050: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001051: Jan  8 08:18:19.354:  feature_vsa is 8AB40BC8,featname is 0
001052: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001053: Jan  8 08:18:19.354: :cc_get_feature_vsa malloc success
001054: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001055: Jan  8 08:18:19.354:  cc_get_feature_vsa count is 7
001056: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001057: Jan  8 08:18:19.354: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055984,feature_id:241
001058: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001059: Jan  8 08:18:19.354: : Generating new featurevsa,inserting it
001060: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001061: Jan  8 08:18:19.354:  feature call forward featname is 3
001062: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001063: Jan  8 08:18:19.354: :cc_free_feature_vsa freeing 8AB40D88
001064: Jan  8 08:18:19.354: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001065: Jan  8 08:18:19.354:  vsacount in free is 6
001066: Jan  8 08:18:19.354: //146/C55712E98029/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Network, Params=0x0, Call Id=146
001067: Jan  8 08:18:19.354: //147/C55712E98029/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=19)
001068: Jan  8 08:18:19.354: //147/C55712E98029/CCAPI/ccCallDisconnect:
   Cause Value=19, Call Entry(Responsed=TRUE, Cause Value=19)
001069: Jan  8 08:18:19.354: //147/C55712E98029/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
001070: Jan  8 08:18:19.362: //147/C55712E98029/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x89720B14, Tag=0x0, Call Id=147,
   Call Entry(Disconnect Cause=19, Voice Class Cause Code=0, Retry Count=0)
001071: Jan  8 08:18:19.362: //147/C55712E98029/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
001072: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001073: Jan  8 08:18:19.362: :cc_free_feature_vsa freeing 8AB40E68
001074: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001075: Jan  8 08:18:19.362:  vsacount in free is 5
001076: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001077: Jan  8 08:18:19.362:  vsacount in free is 4
001078: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001079: Jan  8 08:18:19.362: :cc_get_feature_vsa malloc success
001080: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001081: Jan  8 08:18:19.362:  cc_get_feature_vsa count is 5
001082: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001083: Jan  8 08:18:19.362: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055312,feature_id:242
001084: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_update_feature_vsa:

001085: Jan  8 08:18:19.362:  feature call forward featname is 3
001086: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001087: Jan  8 08:18:19.362: :cc_free_feature_vsa freeing 8AB40BC8
001088: Jan  8 08:18:19.362: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001089: Jan  8 08:18:19.362:  vsacount in free is 4
001090: Jan  8 08:18:19.362: //146/C55712E98029/CCAPI/ccGetTBCTCap:
   TGRM TBCT Enabled; TBCT Capability=0
001091: Jan  8 08:18:19.362: //146/C55712E98029/CCAPI/ccGetTBCTCap:
   TGRM TBCT Enabled; TBCT Capability=0
001092: Jan  8 08:18:19.366: //146/C55712E98029/CCAPI/ccCallDisconnect:
   Cause Value=1, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
001093: Jan  8 08:18:19.366: //146/C55712E98029/CCAPI/ccCallDisconnect:
   Cause Value=1, Call Entry(Responsed=TRUE, Cause Value=1)
001094: Jan  8 08:18:19.366: //146/C55712E98029/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
001095: Jan  8 08:18:19.570: //146/C55712E98029/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x88694EF4, Tag=0x0, Call Id=146,
   Call Entry(Disconnect Cause=1, Voice Class Cause Code=0, Retry Count=0)
001096: Jan  8 08:18:19.570: //146/C55712E98029/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
001097: Jan  8 08:18:19.570: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001098: Jan  8 08:18:19.570: :cc_free_feature_vsa freeing 8AB40CA8
001099: Jan  8 08:18:19.570: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001100: Jan  8 08:18:19.570:  vsacount in free is 3
001101: Jan  8 08:18:19.570: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

001102: Jan  8 08:18:19.570:  vsacount in free is 2

Hope this ist helpful. !

[johschaf]

Hello Bernhard,

I don't see the call hit the voicemail dial-peer. It looks like you made a call from the PSTN to a DID on the UC, correct? Can you verify the ephone-dn that you are calling that you have the following:

ephone-dn  100  dual-line

call-forward busy 39

call-forward noan 39 timeout 20

Can you do another debug, and on that debug just press the voicemail button on a phone?

Were you able to verify you have the following under telephony-service:

telephony-service

voicemail 39

Thanks,

-john

[icoskonstanz]

Hello John,

heres the ephone-dn config :

ephone-dn  597  dual-line
number 26 no-reg primary
pickup-group 1
label Hartmut Wieser
description Hartmut Wieser
name Hartmut Wieser
call-forward busy 39
call-forward noan 39 timeout 20

###############################

here's the debug when i calles the '39' an the voicemail please me to enter the pin :

001123: Jan  9 07:29:19.112: //-1/1D347804824D/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=590926
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=0
   cisco-rdnsi=0
   cisco-redirectreason=0   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

001124: Jan  9 07:29:19.112: //-1/1D347804824D/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x89720B14, Call Info(
   Calling Number=590926,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=20012, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
001125: Jan  9 07:29:19.112: //-1/1D347804824D/CCAPI/ccCheckClipClir:
   In: Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001126: Jan  9 07:29:19.112: //-1/1D347804824D/CCAPI/ccCheckClipClir:
   Out: Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001127: Jan  9 07:29:19.112: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001128: Jan  9 07:29:19.112: :cc_get_feature_vsa malloc success
001129: Jan  9 07:29:19.112: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001130: Jan  9 07:29:19.112:  cc_get_feature_vsa count is 3
001131: Jan  9 07:29:19.112: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001132: Jan  9 07:29:19.112: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327055536,feature_id:282
001133: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown))
001134: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/cc_process_call_setup_ind:
   Event=0x886273B0
001135: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/ccCallSetContext:
   Context=0x8AB5C6C8
001136: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 181 with tag 20012 to app "_ManagedAppProcess_Default"
001137: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/ccCallSetupAck:
   Call Id=181
001138: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
001139: Jan  9 07:29:19.112: //181/1D347804824D/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=TRUE, Tone=Dial Tone,
   Tone Direction=Network, Params=0x0, Call Id=181
001140: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
001141: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
001142: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/ccCallReportDigits:
   (callID=0xB5, digit_event=0x1, enable=TRUE, consume=FALSE)
001143: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=181
001144: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x89720B14, callID=0xB5, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
001145: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x89720B14, Call Id=181
001146: Jan  9 07:29:19.116: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
001147: Jan  9 07:29:19.724: //181/1D347804824D/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=181, Digit=3, DigitBeginFlags=0x0,
   Rtp Timestamp=0x7C7B30, Rtp Expiration=0x0
001148: Jan  9 07:29:19.724: //181/1D347804824D/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=181, Digit=3, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
001149: Jan  9 07:29:19.724: //181/1D347804824D/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
001150: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=181, Digit=9, DigitBeginFlags=0x0,
   Rtp Timestamp=0x7CF830, Rtp Expiration=0x0
001151: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=181, Digit=9, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
001152: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
001153: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/ccCallReportDigits:
   (callID=0xB5, digit_event=0x0, enable=FALSE, consume=FALSE)
001154: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=181
001155: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x89720B14, callID=0xB5, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
001156: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x89720B14, Call Id=181
001157: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
001158: Jan  9 07:29:19.924: //181/1D347804824D/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
001159: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=2000, Params=0x8AB6C018, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
001160: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccCheckClipClir:
   In: Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001161: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccCheckClipClir:
   Out: Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
001162: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccCallSetupRequest:
   Destination Pattern=39, Called Number=39, Digit Strip=FALSE
001163: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccCallSetupRequest:
   Calling Number=590926(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=39(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=Hartmut Wieser
   Account Number=, Final Destination Flag=FALSE,
   Guid=1D347804-5965-11E2-824D-F434DC1856EE, Outgoing Dial-peer=2000
001164: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=590926
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=39
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-rdntype=0
   cisco-rdnplan=0
   cisco-rdnpi=0
   cisco-rdnsi=0
   cisco-redirectreason=0   fwd_final_type =0
   final_redirectNumber =
   hunt_group_timeout =0

001165: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x878676A0, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=590926,(Calling Name=Hartmut Wieser)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=39(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=2000, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
001166: Jan  9 07:29:19.928: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001167: Jan  9 07:29:19.928: :cc_get_feature_vsa malloc success
001168: Jan  9 07:29:19.928: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001169: Jan  9 07:29:19.928:  cc_get_feature_vsa count is 4
001170: Jan  9 07:29:19.928: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

001171: Jan  9 07:29:19.928: :FEATURE_VSA attributes are: feature_name:0,feature_time:2327054864,feature_id:283
001172: Jan  9 07:29:19.928: //182/1D347804824D/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
001173: Jan  9 07:29:19.928: //182/1D347804824D/CCAPI/ccCallSetContext:
   Context=0x8AB6BFC8
001174: Jan  9 07:29:19.928: //181/1D347804824D/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=2000
001175: Jan  9 07:29:19.932: //182/1D347804824D/CCAPI/cc_api_call_proceeding:
   Interface=0x878676A0, Progress Indication=NULL(0)
001176: Jan  9 07:29:20.608: //182/1D347804824D/CCAPI/cc_api_call_alert:
   Interface=0x878676A0, Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
001177: Jan  9 07:29:20.608: //182/1D347804824D/CCAPI/cc_api_call_alert:
   Call Entry(Retry Count=0, Responsed=TRUE)
001178: Jan  9 07:29:20.608: //181/1D347804824D/CCAPI/ccCallAlert:
   Progress Indication=NULL(0), Signal Indication=SIGNAL RINGBACK(1)
001179: Jan  9 07:29:20.608: //181/1D347804824D/CCAPI/ccCallAlert:
   Call Entry(Responsed=TRUE, Alert Sent=TRUE)
001180: Jan  9 07:29:20.608: //181/1D347804824D/CCAPI/ccCallFeature:
   Feature Type=25, Call Id=181
001181: Jan  9 07:29:20.648: //182/1D347804824D/CCAPI/cc_api_call_connected:
   Interface=0x878676A0, Data Bitmask=0x1, Progress Indication=NULL(0),
   Connection Handle=0
001182: Jan  9 07:29:20.648: //182/1D347804824D/CCAPI/cc_api_call_connected:
   Call Entry(Connected=TRUE, Responsed=TRUE, Retry Count=0)
001183: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=FALSE, Tone=Null,
   Tone Direction=Network, Params=0x0, Call Id=181
001184: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/ccConferenceCreate:
   (confID=0x8AC13ABC, callID1=0xB5, gcid=1D347804-596511E2-824DF434-DC1856EE, tag=0x0)
001185: Jan  9 07:29:20.648: //182/1D347804824D/CCAPI/ccConferenceCreate:
   (confID=0x8AC13ABC, callID2=0xB6, gcid=1D347804-596511E2-824DF434-DC1856EE, tag=0x0)
001186: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/ccConferenceCreate:
   Conference Id=0x8AC13ABC, Call Id1=181, Call Id2=182, Tag=0x0
001187: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/cc_api_bridge_done:
   Conference Id=0x36, Source Interface=0x89720B14, Source Call Id=181,
   Destination Call Id=182, Disposition=0x0, Tag=0xFFFFFFFF
001188: Jan  9 07:29:20.648: //182/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001189: Jan  9 07:29:20.648: cc_api_get_xcode_stream : 4702
001190: Jan  9 07:29:20.648: //182/xxxxxxxxxxxx/CCAPI/cc_api_get_xcode_stream:

001191: Jan  9 07:29:20.648: cc_api_get_xcode_stream : 4702
001192: Jan  9 07:29:20.648: //182/1D347804824D/CCAPI/cc_api_bridge_done:
   Conference Id=0x36, Source Interface=0x878676A0, Source Call Id=182,
   Destination Call Id=181, Disposition=0x0, Tag=0x0
001193: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/cc_generic_bridge_done:
   Conference Id=0x36, Source Interface=0x878676A0, Source Call Id=182,
   Destination Call Id=181, Disposition=0x0, Tag=0x0
001194: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x36, Destination Call Id=182)
001195: Jan  9 07:29:20.648: //182/1D347804824D/CCAPI/ccConferenceCreate:
   Call Entry(Conference Id=0x36, Destination Call Id=181)
001196: Jan  9 07:29:20.648: //181/1D347804824D/CCAPI/cc_api_caps_ind:
   Destination Interface=0x878676A0, Destination Call Id=182, Source Call Id=181,
   Caps(Codec=0x1, Fax Rate=0x1, Vad=0x1,
   Modem=0x0, Codec Bytes=20, Signal Type=3)
001197: Jan  9 07:29:20.652: //181/1D347804824D/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))
001198: Jan  9 07:29:20.652: //182/1D347804824D/CCAPI/cc_api_caps_ind:
   Destination Interface=0x89720B14, Destination Call Id=181, Source Call Id=182,
   Caps(Codec=0x1, Fax Rate=0x2, Vad=0x1,
   Modem=0x0, Codec Bytes=160, Signal Type=2)
001199: Jan  9 07:29:20.652: //182/1D347804824D/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))
001200: Jan  9 07:29:20.652: //182/1D347804824D/CCAPI/cc_api_caps_ack:
   Destination Interface=0x89720B14, Destination Call Id=181, Source Call Id=182,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5913)
001201: Jan  9 07:29:20.652: //181/1D347804824D/CCAPI/cc_api_caps_ack:
   Destination Interface=0x878676A0, Destination Call Id=182, Source Call Id=181,
   Caps(Codec=g711ulaw(0x1), Fax Rate=FAX_RATE_VOICE(0x2), Vad=OFF(0x1),
   Modem=OFF(0x0), Codec Bytes=160, Signal Type=2, Seq Num Start=5913)
001202: Jan  9 07:29:20.652: //181/1D347804824D/CCAPI/ccCallConnect:
   Progress Indication=NULL(0), Data Bitmask=0x1
001203: Jan  9 07:29:20.652: //181/1D347804824D/CCAPI/ccCallConnect:
   Call Entry(Connected=TRUE, Responsed=TRUE)
001204: Jan  9 07:29:20.652: //181/1D347804824D/CCAPI/ccCallFeature:
   Feature Type=25, Call Id=181
001205: Jan  9 07:29:20.656: //181/1D347804824D/CCAPI/cc_api_voice_mode_event:
   Call Id=181
001206: Jan  9 07:29:20.656: //181/1D347804824D/CCAPI/cc_api_voice_mode_event:
   Call Entry(Context=0x8AB5C6C8)
001207: Jan  9 07:29:20.656: //181/1D347804824D/CCAPI/cc_process_notify_bridge_done:
   Conference Id=0x36, Call Id1=181, Call Id2=182

########################################

and here's the complete telephony-service config :

telephony-service
video
fxo hook-flash
max-ephones 138
max-dn 600
ip source-address 10.1.1.1 port 2000
auto assign 1 to 1 type bri
calling-number initiator
service phone videoCapability 1
service phone ehookenable 1
service dnis overlay
service dnis dir-lookup
service dss
timeouts interdigit 5
system message Cisco Small Business
url services http://10.1.10.1/voiceview/common/login.do
url authentication http://10.1.10.1/voiceview/authentication/authenticate.do
cnf-file location flash:
cnf-file perphone
user-locale U4 load CME-locale-de_DE-German-8.1.2.2.tar
network-locale U4
load 7941 SCCP41.9-1-1SR1S
load 7941GE SCCP41.9-1-1SR1S
load 7961 SCCP41.9-1-1SR1S
load 7961GE SCCP41.9-1-1SR1S
load 521G-524G cp524g-8-1-17
time-zone 23
time-format 24
date-format dd-mm-yy
dialplan-pattern 1 5909.. extension-length 2
keepalive 30 auxiliary 4
voicemail 39
max-conferences 8 gain -6
call-forward pattern .T
call-forward system redirecting-expanded
moh flash:/media/music-on-hold.au
multicast moh 239.10.16.16 port 2000
web admin system name cisco secret 5 $1$xdzm$dQ00q0.IUYeRDA4lTuNIl1
dn-webedit
time-webedit
transfer-system full-consult dss
transfer-pattern .T
transfer-pattern 0.T
transfer-pattern 6.. blind
secondary-dialtone 0
night-service day Sun 17:00 09:00
night-service day Mon 17:00 09:00
night-service day Tue 17:00 09:00
night-service day Wed 17:00 09:00
night-service day Thu 17:00 09:00
night-service day Fri 17:00 09:00
night-service day Sat 17:00 09:00
fac standard
create cnf-files version-stamp Jan 01 2002 00:00:00

Greets !

[johschaf]

Hello Bernhard,

So you can access voicemail locally via the phone? If so, what is the call flow when the voicemail doesn't pickup?

Thanks,

-john

[icoskonstanz]

Hello John,

yes i can access the voicemail locally via phone ! When i call from external and the timout of 20 sec. with no answer is reached, the call hangs up / disconnect !

Greets

[johschaf]

Hello Bernard,

What is the call flow from the PSTN? Does the incoming call first hit a blast group or hunt group?

Thanks,

-john