Specification Of R2 Signaling The Digital R2 Signaling shall be based on ITU-T Q.421 recommendations. The Time Slot 16 (TS16) 2-bit coding is as following, Bit Forward Backward Operating Condition a b a B Idle 1 0 1 0 Seizure 0 0 1 0 Seizure Acknowledge 0 0 1 1 ;Answer 0 0 0 1 Clear Back 0 0 1 1 Clear Back 1 0 1 1 Clear Back 1 0 1 0 Clear Forward 1 0 0 1 Clear Forward 1 0 1 1 Clear Forward 1 0 1 0 Forced Release 0 0 0 0 Blocking 1 0 1 1 =============================================== Gateway#sh debug CAS: Channel Associated Signaling debugging is on Call Switching Module: CSM Voice Call Debug debugging is on Call direction: H323-->AS5350-->E1 Interface-->Swtich-->PSTN =================Countrry as ITU=================== Feb 11 22:14:18.163: //-1/xxxxxxxxxxxx/VTSP:(3/2:0):-1:-1:-1/vtsp_allocate_cdb: CDB=0x65E767A0 Feb 11 22:14:18.163: //-1/xxxxxxxxxxxx/VTSP:(3/2:0):-1:-1:-1/vtsp_insert_cdb: Feb 11 22:14:18.163: //-1/xxxxxxxxxxxx/VTSP:(3/2:0):-1:-1:-1/vtsp_process_event: [state:INVALID STATE MACHINE, event:E_CC_SETUP_REQ] Feb 11 22:14:18.163: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:-1:-1/vtsp_og_call_req: Echo Cancel=TRUE, Gain Control=0 Auto Gain Control=FALSE, Dual Tone Detect Mask=0x0, Seq Tone Detect=FALSE Feb 11 22:14:18.163: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:-1:-1/vtsp_do_call_setup_req: Feb 11 22:14:18.163: //CSM/vdev_allocate: v(2/53):Switching to voice Feb 11 22:14:18.163: //CSM/csm_install_voice_vdev_vector_common: v(2/53):vtsp_cdb=0x00000000 Feb 11 22:14:18.163: //CSM/vdev_allocate: v(2/53) is allocated Feb 11 22:14:18.163: //NP DSPLIB/np_dsplib_voice_stop_service: (2/53):dsplib_state=DSPLIB_IDLE Feb 11 22:14:18.163: //NP DSPLIB/np_dsplib_prepare_voice: (2/53):svc_type=0x1300 Feb 11 22:14:18.187: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_VOICE_INIT Voice session transition to IDLE Feb 11 22:14:18.187: //NP DSPLIB/np_dsplib_voice_start_service: (2/53):dsplib_state=DSPLIB_VOICE_START Feb 11 22:14:18.271: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_VOICE_WAIT_ACTIVE Voice session transition to ACTIVE Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_dsm_opened_cb: Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_process_event: [state:S_SETUP_REQ_PEND, event:E_VTSP_DSM_OPEN_SUCCESS] Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/act_setup_req_pend_succ: Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_request_call: Mode=0, Peer=8800 Feb 11 22:14:18.271: digit_strip:1, pcn:88029563040, poa:880T Feb 11 22:14:18.271: pcn:29563040, poa:T Feb 11 22:14:18.271: Final pcn:29563040, poa:T, dial_string:29563040 Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_create_call_active_on_setup_req: Target Carrier ID= Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_create_call_active_on_setup_req: Source Carrier ID= Feb 11 22:14:18.271: //CSM/csm_vtsp_call_setup_request_np: v(2/53):vtsp_cdb=0x65E767A0 vtsp_sdb=0x65BB2970address=29563040 called#=88029563040 calling#=19051234567 Feb 11 22:14:18.271: //CSM/send_vtsp_setup_request_to_csm: address=29563040 calling_number=19051234567 pcm_encoding=1 octet_mask=0x0 Feb 11 22:14:18.271: //CSM/send_vtsp_setup_request_to_csm: calling_oct3a=0x0 called_oct3=0x91 octet_mask=0x5 present_ind=0 Feb 11 22:14:18.271: //CSM/send_vtsp_setup_request_to_csm: octet_mask=0x25 info_trans_cap=0x0 calling number=19051234567 oct3=0x91 oct3a=0x0 called number=29563040 oct3=0x91 Feb 11 22:14:18.271: //CSM/csm_proc_idle: v(2/53):[CSM_IDLE_STATE:CSM_EVENT_MODEM_OFFHOOK] Feb 11 22:14:18.271: //CSM/csm_get_signaling_channel: bchan_num=0xFFFFFFFF dchan_index=0 next_index=0 Feb 11 22:14:18.271: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):-1:0:0/vtsp_request_call: Progress Indication=0, Info Trans Capability=0 Calling Number=19051234567, TON=International, NPI=ISDN, Screening=Not Screened, Presentation=Allowed Called Number=88029563040, TON=International, NPI=ISDN Feb 11 22:14:18.279: from Trunk(3): DMFC: Got sig@ u1343952 c2 tone0 group3 Feb 11 22:14:18.279: //CSM/csm_proc_oc4_dialing: v(2/53):[CSM_OC4_DIALING:CSM_EVENT_DSX0_BCHAN_ASSIGNED] Feb 11 22:14:18.279: //CSM/csm_connect_pri_vdev: v(2/53):TS allocated at bp_stream=7 bp_channel=86 vdev_common=0x64FBA9A0 Feb 11 22:14:18.279: //NP DSPLIB/np_dsplib_prepare_voice_for_oc: (2/53) Feb 11 22:14:18.279: //CSM/csm_vtsp_setup_for_oc: v(2/53):voice_vdev=0x64DF50C8 Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_SETUP_REQUEST, event:E_TSP_PROCEEDING] Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_pend_proceeding: Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_timer: Timer Start Time=2139388, Timer Value=10000(ms) Feb 11 22:14:18.279: from Trunk(3): [0x83F8]:(2/0): Tx SEIZURE (ABCD=0001) Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_pend_proceeding: Peer=8800, Progress Indication=0, Return Code=2 Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_opened_cb: Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_NULL, event:E_VTSP_DSM_OPEN_SUCCESS] Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_req_wait_pend_success: Event=72 Feb 11 22:14:18.279: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_timer_stop: Timer Stop Time=2139388 Feb 11 22:14:18.311: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_SIG_INIT Voice session transition to FLUSHING Feb 11 22:14:18.311: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_SIG_INIT Voice session transition to IDLE Feb 11 22:14:18.491: from Trunk(3): [0x83F8]:(2/0): Rx SEIZURE_ACK (ABCD=1101) Feb 11 22:14:18.531: //CSM/csm_proc_oc4_dialing: v(2/53):[CSM_OC4_DIALING:CSM_EVENT_DSX0_START_TX_TONE] Feb 11 22:14:18.531: //CSM/np_voice_start_dialing: v(2/53). Feb 11 22:14:19.207: //NP DSPLIB/np_dsplib_dial_dnis: (2/53):DNIS=29563040 ANI=19051234567 Feb 11 22:14:19.207: //SIGSM/sig_r2_process_out_comp: sig_r2_state=0 sig_r2_event=19 Feb 11 22:14:19.207: //SIGSM/sig_r2_out_initialize: dnis=29563040 ani=19051234567 Feb 11 22:14:19.207: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:19.207: //SIGSM/sig_r2_send_digit: Digit=2 Pulse=0 Feb 11 22:14:19.479: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:19.479: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:19.743: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:19.743: //SIGSM/sig_r2_send_digit: Digit=9 Pulse=0 Feb 11 22:14:20.015: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:20.015: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:20.279: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:20.279: //SIGSM/sig_r2_send_digit: Digit=5 Pulse=0 Feb 11 22:14:20.555: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:20.555: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:20.811: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:20.811: //SIGSM/sig_r2_send_digit: Digit=6 Pulse=0 Feb 11 22:14:21.091: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:21.091: //SIGSM/sig_r2_send_caller: Feb 11 22:14:21.091: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:21.351: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:21.351: //SIGSM/sig_r2_send_digit: Digit=1 Pulse=0 Feb 11 22:14:21.627: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:21.627: //SIGSM/sig_r2_send_caller: Feb 11 22:14:21.627: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:21.887: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:21.887: //SIGSM/sig_r2_send_digit: Digit=1 Pulse=0 Feb 11 22:14:22.159: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:22.159: //SIGSM/sig_r2_send_caller: Feb 11 22:14:22.159: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:22.423: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:22.423: //SIGSM/sig_r2_send_digit: Digit=9 Pulse=0 Feb 11 22:14:22.695: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:22.695: //SIGSM/sig_r2_send_caller: Feb 11 22:14:22.695: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:22.951: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:22.951: //SIGSM/sig_r2_send_digit: Digit=10 Pulse=0 Feb 11 22:14:23.223: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:23.223: //SIGSM/sig_r2_send_caller: Feb 11 22:14:23.223: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:23.487: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:23.487: //SIGSM/sig_r2_send_digit: Digit=5 Pulse=0 Feb 11 22:14:23.759: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:23.759: //SIGSM/sig_r2_send_caller: Feb 11 22:14:23.759: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:24.015: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:24.015: //SIGSM/sig_r2_send_digit: Digit=1 Pulse=0 Feb 11 22:14:24.279: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:24.279: //SIGSM/sig_r2_send_caller: Feb 11 22:14:24.279: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:24.531: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:24.531: //SIGSM/sig_r2_send_digit: Digit=2 Pulse=0 Feb 11 22:14:24.803: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:24.803: //SIGSM/sig_r2_send_caller: Feb 11 22:14:24.803: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:25.063: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:25.063: //SIGSM/sig_r2_send_digit: Digit=3 Pulse=0 Feb 11 22:14:25.339: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:25.339: //SIGSM/sig_r2_send_caller: Feb 11 22:14:25.339: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:25.599: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:25.599: //SIGSM/sig_r2_send_digit: Digit=4 Pulse=0 Feb 11 22:14:25.871: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:25.871: //SIGSM/sig_r2_send_caller: Feb 11 22:14:25.871: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:26.127: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:26.127: //SIGSM/sig_r2_send_digit: Digit=5 Pulse=0 Feb 11 22:14:26.395: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:26.395: //SIGSM/sig_r2_send_caller: Feb 11 22:14:26.395: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:26.655: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:26.655: //SIGSM/sig_r2_send_digit: Digit=6 Pulse=0 Feb 11 22:14:26.927: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:26.927: //SIGSM/sig_r2_send_caller: Feb 11 22:14:26.927: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:27.183: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:27.183: //SIGSM/sig_r2_send_digit: Digit=7 Pulse=0 Feb 11 22:14:27.455: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=5 Feb 11 22:14:27.455: //SIGSM/sig_r2_send_caller: Feb 11 22:14:27.455: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:27.711: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:27.711: //SIGSM/sig_r2_send_digit: Digit=15 Pulse=0 Feb 11 22:14:27.979: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:27.979: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:28.235: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:28.235: //SIGSM/sig_r2_send_digit: Digit=3 Pulse=0 Feb 11 22:14:28.503: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:28.503: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:28.771: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:28.771: //SIGSM/sig_r2_send_digit: Digit=10 Pulse=0 Feb 11 22:14:29.043: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:29.043: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:29.299: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:29.299: //SIGSM/sig_r2_send_digit: Digit=4 Pulse=0 Feb 11 22:14:29.567: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=1 Feb 11 22:14:29.567: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:29.831: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=16 Feb 11 22:14:29.831: //SIGSM/sig_r2_send_digit: Digit=10 Pulse=0 Feb 11 22:14:30.427: //SIGSM/sig_r2_process_out_comp: sig_r2_state=1 sig_r2_event=3 Feb 11 22:14:30.427: //SIGSM/sig_r2_send_cat_b: Feb 11 22:14:30.427: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:30.691: //SIGSM/sig_r2_process_out_comp: sig_r2_state=2 sig_r2_event=16 Feb 11 22:14:30.691: //SIGSM/sig_r2_send_digit: Digit=1 Pulse=0 Feb 11 22:14:30.963: //SIGSM/sig_r2_process_out_comp: sig_r2_state=2 sig_r2_event=7 Feb 11 22:14:30.963: //SIGSM/sig_r2_address_complete: Feb 11 22:14:30.963: //SIGSM/sig_r2_send_digit: Digit=16 Pulse=0 Feb 11 22:14:31.223: //SIGSM/sig_r2_process_out_comp: sig_r2_state=5 sig_r2_event=16 Feb 11 22:14:31.227: //NP DSPLIB/np_dsplib_process_signal_generated: (2/53):called number=29563040 Feb 11 22:14:31.227: //CSM/csm_vtsp_process_dialing_complete: v(2/53):(cdb=0x65E767A0, vdev_common=0x64FBA9A0,voice_vdev=0x64DF50C8) Feb 11 22:14:31.227: //CSM/csm_vtsp_process_dialing_complete: v(2/53):type=2, val=7, country=0 Feb 11 22:14:31.227: //CSM/csm_proc_oc4_dialing: v(2/53):[CSM_OC4_DIALING:CSM_EVENT_ADDR_INFO_COLLECTED] Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_TSP_ALERT] Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_pend_alert: Progress Indication=8, Signal Indication=1 Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_ring_noan_timer_start: Timer Start Time=2140683, No Answer Timer Value=180000(ms) Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_pend_alert: Ringback Indication=FALSE, Ring Timeout=180(s) Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_bridge_check_cb: Feb 11 22:14:31.227: //CSM/csm_vtsp_call_bridged: v(2/53):Call Bridged (vtsp_cdb=0x65E767A0, vdev_info=0x64DF50C8) Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_bridge_check_cb:exit@1122 Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_bridge_status_cb: Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_save_fax_config: Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_save_fax_config: Fax Relay=ENABLED Primary Fax Protocol=T38_FAX_RELAY, Fallback Fax Protocol=CISCO_FAX_RELAY Fax Parameters Set By=Dialpeer, Peer=880 Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_save_fax_config: LS Red=0, HS Red=0 Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_set_fax_feat_param: Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_DO_CAPS_IND] Feb 11 22:14:31.227: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_IND Feb 11 22:14:31.231: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_CC_CAPS_IND] Feb 11 22:14:31.231: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK Feb 11 22:14:31.231: //NP DSPLIB/np_dsplib_voice_stop_service: (2/53):dsplib_state=DSPLIB_SIG_ACTIVE Feb 11 22:14:31.231: //NP DSPLIB/np_dsplib_voice_stop_service: (2/53):dsplib_state=DSPLIB_IDLE Feb 11 22:14:31.231: //NP DSPLIB/np_dsplib_prepare_voice: (2/53):svc_type=0x1300 Feb 11 22:14:31.263: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_VOICE_INIT Voice session transition to IDLE Feb 11 22:14:31.263: //CSM/np_voice_process_voice_service_changed: v(2/53). Feb 11 22:14:31.263: //NP DSPLIB/np_dsplib_voice_start_service: (2/53):dsplib_state=DSPLIB_VOICE_START Feb 11 22:14:31.347: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_VOICE_WAIT_ACTIVE Voice session transition to ACTIVE Feb 11 22:14:31.347: //CSM/np_voice_process_voice_service_active: v(2/53). Feb 11 22:14:31.347: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 Feb 11 22:14:31.347: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback: Feb 11 22:14:31.347: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback:exit@943 Feb 11 22:14:31.347: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MODIFY_MEDIA_IND Feb 11 22:14:50.551: from Trunk(3): [0x83F8]:(2/0): Rx ANSWERED (ABCD=0101) Feb 11 22:14:50.595: //CSM/csm_proc_oc5_wait_for_carrier: v(2/53):[CSM_OC5_WAIT_FOR_CARRIER:CSM_EVENT_DSX0_CONNECTED] Feb 11 22:14:50.595: //CSM/vtsp_confirm_oc: voice_vdev=0x64DF50C8 Feb 11 22:14:50.595: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_SETUP_REQ_PROC, event:E_TSP_CONNECT] Feb 11 22:14:50.595: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_setup_pend_connect: Progress Indication=2 Feb 11 22:14:50.595: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_ring_noan_timer_stop: Timer Stop Time=2142620 Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_CONNECT, event:E_CC_CAPS_IND] Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_CAPS_ACK Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback: Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback:exit@943 Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MODIFY_MEDIA_IND Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_feature_notify_cb: Feature ID=0, Feature Status=1 Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback: Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_reactivate_ringback:exit@943 Feb 11 22:14:50.863: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_peer_event_cb: Event=E_DSM_CC_MODIFY_MEDIA_IND Feb 11 22:15:05.743: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_dsm_bridge_status_cb: Feb 11 22:15:05.743: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_CONNECT, event:E_CC_DISCONNECT] Feb 11 22:15:05.743: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_disconnect: Cause Value=127 Feb 11 22:15:05.743: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_ring_noan_timer_stop: Timer Stop Time=2144135 Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_WAIT_STATS, event:E_VTSP_DSM_STATS_COMPLETE] Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_dsm_dsp_stats_complete: Feb 11 22:15:05.751: //CSM/csm_vtsp_call_disconnect_np: tdm_hpm=0 cause=127 bp_stream=0 bp_ch=0 Feb 11 22:15:05.751: //CSM/csm_proc_ic7_oc6_connected: v(2/53):[CSM_OC6_CONNECTED:CSM_EVENT_MODEM_ONHOOK] Feb 11 22:15:05.751: //CSM/csm_enter_disconnecting_state: v(2/53) Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_timer: Timer Start Time=2144136, Timer Value=60000(ms) Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_process_event: [state:S_WAIT_RELEASE, event:E_TSP_DISCONNECT_CONF] Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/act_wrelease_release: Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_play_busy_timer_stop: Timer Stop Time=2144136 Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_timer_stop: Timer Stop Time=2144136 Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_do_call_history: Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:0:0/vtsp_do_call_history: Coder Rate=16 Feb 11 22:15:05.751: //NP DSPLIB/np_dsplib_voice_stop_service: (2/53):dsplib_state=DSPLIB_VOICE_ACTIVE Feb 11 22:15:05.751: //CSM/vdev_deallocate: v(2/53):Switching back to modem service Feb 11 22:15:05.751: //CSM/vdev_deallocate: v(2/53) is deallocated. vdev_status=0x803 vdev_flags=0x2 service=0x0 Feb 11 22:15:05.751: from Trunk(3): [0x83F8]:(2/0): Tx IDLE (ABCD=1001) Feb 11 22:15:05.751: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:-1:-1/vtsp_dsm_closed_cb: Feb 11 22:15:05.755: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:-1:-1/vtsp_process_event: [state:S_CLOSE_DSPRM, event:E_VTSP_DSM_CLOSE_COMPLETE] Feb 11 22:15:05.755: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:-1:-1/act_terminate: Feb 11 22:15:05.755: //2087/xxxxxxxxxxxx/VTSP:(3/2:0):0:-1:-1/vtsp_timer_stop: Timer Stop Time=2144136 Feb 11 22:15:05.755: //-1/xxxxxxxxxxxx/VTSP:(3/2:0):0:-1:-1/vtsp_free_cdb: CDB=0x65E767A0 Feb 11 22:15:05.775: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_IDLE Voice session transition to FLUSHING Feb 11 22:15:05.775: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (2/53):DSPLIB_IDLE Voice session transition to IDLE Feb 11 22:15:05.967: from Trunk(3): [0x83F8]:(2/0): Rx IDLE (ABCD=1001) Feb 11 22:15:06.635: //NP DSPLIB/np_dsplib_notify_csm_onhook: (2/53):Modem went onhook Feb 11 22:15:06.635: //CSM/csm_proc_ic8_oc8_disconnecting: v(2/53):[CSM_OC8_DISCONNECTING:CSM_EVENT_MODEM_ONHOOK] Feb 11 22:15:06.635: //CSM/csm_enter_idle_state: v(2/53) Feb 11 22:15:06.635: //CSM/vdev_deallocate: v(2/53) is deallocated. vdev_status=0x0 vdev_flags=0x0 service=0x0 ============= Country as INDIA ================ Feb 11 21:36:37.259: //CSM/vdev_allocate: v(1/77):Switching to voice Feb 11 21:36:37.259: //CSM/csm_install_voice_vdev_vector_common: v(1/77):vtsp_cdb=0x00000000 Feb 11 21:36:37.259: //CSM/vdev_allocate: v(1/77) is allocated Feb 11 21:36:37.259: //NP DSPLIB/np_dsplib_voice_stop_service: (1/77):dsplib_state=DSPLIB_IDLE Feb 11 21:36:37.259: //NP DSPLIB/np_dsplib_prepare_voice: (1/77):svc_type=0x1300 Feb 11 21:36:37.279: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (1/77):DSPLIB_VOICE_INIT Voice session transition to IDLE Feb 11 21:36:37.279: //NP DSPLIB/np_dsplib_voice_start_service: (1/77):dsplib_state=DSPLIB_VOICE_START Feb 11 21:36:37.363: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (1/77):DSPLIB_VOICE_WAIT_ACTIVE Voice session transition to ACTIVE Feb 11 21:36:37.363: //CSM/csm_vtsp_call_setup_request_np: v(1/77):vtsp_cdb=0x65E76A48 vtsp_sdb=0x65BB2970address=29563040 called#=88029563040 calling#=19051234567 Feb 11 21:36:37.363: //CSM/send_vtsp_setup_request_to_csm: address=29563040 calling_number=19051234567 pcm_encoding=1 octet_mask=0x0 Feb 11 21:36:37.363: //CSM/send_vtsp_setup_request_to_csm: calling_oct3a=0x0 called_oct3=0x91 octet_mask=0x5 present_ind=0 Feb 11 21:36:37.363: //CSM/send_vtsp_setup_request_to_csm: octet_mask=0x25 info_trans_cap=0x0 calling number=19051234567 oct3=0x91 oct3a=0x0 called number=29563040 oct3=0x91 Feb 11 21:36:37.363: //CSM/csm_proc_idle: v(1/77):[CSM_IDLE_STATE:CSM_EVENT_MODEM_OFFHOOK] Feb 11 21:36:37.363: //CSM/csm_get_signaling_channel: bchan_num=0xFFFFFFFF dchan_index=25632 next_index=0 Feb 11 21:36:37.367: from Trunk(3): DMFC: Got sig@ u1343952 c2 tone0 group3 Feb 11 21:36:37.371: //CSM/csm_proc_oc4_dialing: v(1/77):[CSM_OC4_DIALING:CSM_EVENT_DSX0_BCHAN_ASSIGNED] Feb 11 21:36:37.371: //CSM/csm_connect_pri_vdev: v(1/77):TS allocated at bp_stream=7 bp_channel=82 vdev_common=0x64CA2318 Feb 11 21:36:37.371: //NP DSPLIB/np_dsplib_prepare_voice_for_oc: (1/77) Feb 11 21:36:37.371: //CSM/csm_vtsp_setup_for_oc: v(1/77):voice_vdev=0x64ABF298 Feb 11 21:36:37.371: from Trunk(3): [0x83F4]:(2/0): Tx SEIZURE (ABCD=0001) Feb 11 21:36:37.403: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (1/77):DSPLIB_SIG_INIT Voice session transition to FLUSHING Feb 11 21:36:37.403: //NP DSPLIB/np_dsplib_voice_state_change_ntf_handler: (1/77):DSPLIB_SIG_INIT Voice session transition to IDLE Feb 11 21:36:37.583: from Trunk(3): [0x83F4]:(2/0): Rx SEIZURE_ACK (ABCD=1101) Feb 11 21:36:37.627: //CSM/csm_proc_oc4_dialing: v(1/77):[CSM_OC4_DIALING:CSM_EVENT_DSX0_START_TX_TONE] Feb 11 21:36:37.627: //CSM/np_voice_start_dialing: v(1/77). Feb 11 21:36:38.299: //NP DSPLIB/np_dsplib_dial_dnis: (1/77):DNIS=29563040 ANI=19051234567 Feb 11 21:36:51.647: //NP DSPLIB/np_dsplib_process_sigif_failure_notify: (1/77):reason=R2 state machine reports failure Feb 11 21:36:51.647: //CSM/csm_proc_oc4_dialing: v(1/77):[CSM_OC4_DIALING:CSM_EVENT_MODEM_ONHOOK] Feb 11 21:36:51.647: //CSM/np_voice_call_hangup: v(1/77):reason=0 type=1. Feb 11 21:36:51.651: //CSM/csm_enter_disconnecting_state: v(1/77) Feb 11 21:36:51.651: from Trunk(3): [0x83F4]:(2/0): Tx IDLE (ABCD=1001) Feb 11 21:36:51.651: //NP DSPLIB/np_dsplib_voice_stop_service: (1/77):dsplib_state=DSPLIB_IDLE Feb 11 21:36:51.651: //CSM/vdev_deallocate: v(1/77):Switching back to modem service Feb 11 21:36:51.651: //CSM/vdev_deallocate: v(1/77) is deallocated. vdev_status=0x803 vdev_flags=0x2 service=0x0 Feb 11 21:36:51.655: //NP DSPLIB/np_dsplib_notify_csm_onhook: (1/77):Modem went onhook Feb 11 21:36:51.655: //CSM/csm_proc_ic8_oc8_disconnecting: v(1/77):[CSM_OC8_DISCONNECTING:CSM_EVENT_MODEM_ONHOOK] Feb 11 21:36:51.655: //CSM/csm_enter_idle_state: v(1/77) Feb 11 21:36:51.655: //CSM/vdev_deallocate: v(1/77) is deallocated. vdev_status=0x0 vdev_flags=0x2 service=0x0 Feb 11 21:36:51.951: from Trunk(3): [0x83F4]:(2/0): Rx IDLE (ABCD=1001)