IP-IVR bug

Version 1
    This document was generated from CDN thread

    Created by: LEON MCCALLA on 26-08-2011 04:34:53 PM
    can i post a bug here and have someone forward it to TAC please.
     
    Leon McCalla
     
    on IOS 124-15.T14 some devices are not playing TCL audio to IP legs. after many hours of debugs on different IOS and different devices I have evidence to support my statement above. all tests were done with a device making a SIP call into a cisco router which is configured to play IP-IVR. The same TCL and the same IVR files are being used in all cases. here is a summary of the outcome.
     
    IP device -> 1760  (124-15.T14)  IVR works
    IP device -> 3725  (124-15.T14)   IVR fails
    IP device -> 3725  (124-25a)   IVR works
     
    here are the "debug voip appl media" traces showing the outcome..
     
    3725-14T
    ------------------------------------------------
    Aug 26 15:43:01.214: //22//MSM :/ms_get_packet_size:
    Aug 26 15:43:01.214:  ms_get_packet_size NO Voice Class codec
    Aug 26 15:43:01.214: //22//MSM :/ms_associate: packet_size = 160 timestamp increment = 160 packet_duration = 20 Coder = 5 vad = 0 SampleRate = 8000
    Aug 26 15:43:01.214: //22//MSM :/ms_associate: >>ccAssociateStream()
    Aug 26 15:43:01.222: //22//MSM :LP:MS15:/ms_associateDone: 
    Aug 26 15:43:01.222: //22//MSM :/ms_asDone_buginf: callID=0x16, pVdb=0x65C760A8,
        disposition=-5, playFunc=0x624E1478,
        codec=0x5=g711ulaw, vad=0,
        mediaType=3, streamAssocID=23
    Aug 26 15:43:01.222: //22//MSM :/ms_stop_play: mgdTstop at 00:13:04.572 (cause MS_STOP_SETUPFAIL)
    Aug 26 15:43:01.222: //22//MSM :/ms_stop_play: Play Stopped at 00:13:04.572
    Aug 26 15:43:01.222: //22//MSM :/ms_stop_play: calling CBF for stream_id 1
    Aug 26 15:43:01.222: //22//MSW :/msu_synth_ms_play_complete: context=0x65773840, use_dynamic=1, dynamicElement=0x663AB380,
                cause=MS_STOP_SETUPFAIL, stream_id=1, duration=0, rate=0 proto_code=0
    Aug 26 15:43:01.222: //22//MSW :/msu_synth_ms_play_complete:
    Aug 26 15:43:01.222: //22//MSW :/msu_call_app: app_cbf=0x61B17468
            Event = MSW_EV_SYNTHESIZER(1), Context 0x662B6334, Type    MSW_SYNTH_TYPE_SYNTHESIZE(2), Reason  MSW_SYNTH_REASON_SETUPFAIL(12)

    1760-14T
    ----------------------------------------------------------------------
    Aug 26 15:40:59.282: //49//MSM :/ms_associate: packet_size = 240 timestamp increment = 240 packet_duration = 30 Coder = 5 vad = 0 SampleRate = 8000
    Aug 26 15:40:59.282: //49//MSM :/ms_associate: >>ccAssociateStream()
    Aug 26 15:40:59.290: //49//MSM :LP:MS13:/ms_associateDone: 
    Aug 26 15:40:59.290: //49//MSM :/ms_asDone_buginf: callID=0x31, pVdb=0x852AC9F0,
        disposition=0, playFunc=0x802CA2B0,
        codec=0x5=g711ulaw, vad=0,
        mediaType=6, streamAssocID=50
    Aug 26 15:40:59.290: //49//MSM :/ms_associateDone: Setting initial seqnum=4082 for the call
    Aug 26 15:40:59.290: //-1//MCM :MR8:/mc_setup_reader_encaps:  SSRC: 0x0 SeqNum: 0xFF2
    Aug 26 15:40:59.290: //49//MSM :/ms_associateDone: First Buf Play at 07:17:34.952 of en_welcome.au
    Aug 26 15:40:59.290: //49//MSM :/ms_associateDone: 07:17:34.952, Tstart(ply: iSndDly 0)
    Aug 26 15:40:59.294: //-1//MCM :MR8:/mc_make_packets_DQ: mc:83F51ED0 name:en_welcome.au


    3725-25b
    ---------------------------------------------------
     Aug 26 16:14:12.842:  ms_get_packet_size NO Voice Class codec
    Aug 26 16:14:12.842: //7//MSM :/ms_associate: packet_size = 160 timestamp increment = 160 packet_duration = 20 Coder = 5 vad = 0 SampleRate = 8000
    Aug 26 16:14:12.842: //7//MSM :/ms_associate: >>ccAssociateStream()
    Aug 26 16:14:12.846: //7//MSM :LP:MS5:/ms_associateDone: 
    Aug 26 16:14:12.846: //7//MSM :/ms_asDone_buginf: callID=0x7, pVdb=0x6583C328,
        disposition=0, playFunc=0x62296140,
        codec=0x5=g711ulaw, vad=0,
        mediaType=3, streamAssocID=8
    Aug 26 16:14:12.846: //-1//MCM :MR3:/mc_setup_reader_encaps: 
    Aug 26 16:14:12.846: //7//MSM :/ms_associateDone: First Buf Play at 00:02:22.432 of en_welcome.au
    Aug 26 16:14:12.846: //7//MSM :/ms_associateDone: 00:02:22.432, Tstart(ply: iSndDly 0)
    Aug 26 16:14:12.850: //-1//MCM :MR3:/mc_make_packets_DQ: mc:6642B6A8 name:en_welcome.au

    Subject: RE: IP-IVR bug
    Replied by: Yaw-Ming Chen on 26-08-2011 04:40:56 PM
    Since it works it later version I think they will consider "bug is fixed"

    IP device -> 3725  (124-15.T14)   IVR fails
    IP device -> 3725  (124-25a)   IVR works