May 21 11:06:24.030: htsp_process_event: [0/2/3, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing May 21 11:06:24.030: htsp_timer - 125 msec May 21 11:06:24.158: htsp_process_event: [0/2/3, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer May 21 11:06:24.158: htsp_timer - 10000 msec May 21 11:06:24.158: htsp_timer3 - 5600 msec May 21 11:06:24.158: [0/2/3] htsp_start_caller_id_rx:BELLCORE May 21 11:06:24.158: [0/2/3] htsp_dsm_create_success returns 1 May 21 11:06:24.626: htsp_process_event: [0/2/3, FXOLS_RINGING, E_DSP_SIG_0100] May 21 11:06:24.626: fxols_ringing_not May 21 11:06:24.626: htsp_timer_stop May 21 11:06:24.626: htsp_timer - 10000 msec May 21 11:06:26.210: [0/2/3] htsp_dsm_feature_notify_cb returns 2 id=DSM_FEATURE_SM_CALLERID_RX May 21 11:06:26.214: htsp_process_event: [0/2/3, FXOLS_RINGING, E_HTSP_CALLERID_RX_DONE] May 21 11:06:26.214: htsp_timer_stop May 21 11:06:26.214: [0/2/3] htsp_stop_caller_id_rx. message length 170htsp_setup_ind May 21 11:06:26.214: [0/2/3] get_fxo_caller_id:Caller ID received. Message type=128 length=64 checksum=5B May 21 11:06:26.214: [0/2/3] Caller ID String 80 3D 01 08 30 35 32 31 31 31 30 36 02 0B 38 39 32 36 32 32 36 34 34 30 34 03 0B 38 34 39 39 32 36 34 33 34 32 32 11 01 01 16 01 0A 30 11 45 57 53 44 20 66 72 6F 6D 20 53 49 45 4D 45 4E 53 5B May 21 11:06:26.214: [0/2/3] get_fxo_caller_id calling num=89262264404 calling name= calling time=05/21 11:06 May 21 11:06:26.218: fxols_callerid_done: call being answered May 21 11:06:26.218: //-1/4321998F96F4/CCAPI/cc_api_display_ie_subfields: cc_api_call_setup_ind_common: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=09262264404 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 May 21 11:06:26.222: //-1/4321998F96F4/CCAPI/cc_api_call_setup_ind_common: Interface=0x476EA444, Call Info( Calling Number=09262264404,(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=79100, 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 May 21 11:06:26.222: //-1/4321998F96F4/CCAPI/ccCheckClipClir: In: Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) May 21 11:06:26.222: //-1/4321998F96F4/CCAPI/ccCheckClipClir: Out: Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) May 21 11:06:26.222: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:26.222: :cc_get_feature_vsa malloc success May 21 11:06:26.222: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:26.222: cc_get_feature_vsa count is 1 May 21 11:06:26.222: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:26.222: :FEATURE_VSA attributes are: feature_name:0,feature_time:1198145240,feature_id:1165 May 21 11:06:26.222: //3640/4321998F96F4/CCAPI/cc_api_call_setup_ind_common: Set Up Event Sent; Call Info(Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=(TON=Unknown, NPI=Unknown)) May 21 11:06:26.226: //3640/4321998F96F4/CCAPI/cc_process_call_setup_ind: Event=0x476B0F50 May 21 11:06:26.230: //3640/4321998F96F4/CCAPI/ccCallSetContext: Context=0x488AE658 May 21 11:06:26.230: //3640/4321998F96F4/CCAPI/cc_process_call_setup_ind: >>>>CCAPI handed cid 3640 with tag 79100 to app "_ManagedAppProcess_Default" May 21 11:06:26.234: [0/2/3] htsp_dsm_close_done May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/ccCallSetupAck: Call Id=3640 May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/cc_api_set_transfer_info: Transfer Number=, Transfer Reason=0x0 May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=TRUE, Tone=Dial Tone, Tone Direction=Network, Params=0x0, Call Id=3640 May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/ccSetDigitTimeouts: Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms) May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/ccSetDigitTimeouts: Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms)) May 21 11:06:26.234: //3640/xxxxxxxxxxxx/CCAPI/ccCallReportDigits: (callID=0xE38, digit_event=0x1, enable=TRUE, consume=FALSE) May 21 11:06:26.234: //3640/4321998F96F4/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=3640 May 21 11:06:26.238: //3640/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x476EA444, callID=0xE38, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE) May 21 11:06:26.238: //3640/4321998F96F4/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x476EA444, Call Id=3640 May 21 11:06:26.238: //3640/4321998F96F4/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms)) May 21 11:06:26.238: htsp_process_event: [0/2/3, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] May 21 11:06:26.238: fxols_wait_setup_ack: May 21 11:06:26.238: [0/2/3] set signal state = 0xC timestamp = 0fxols_check_auto_call May 21 11:06:26.530: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_DSP_SIG_0110]fxols_rvs_battery May 21 11:06:29.758: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_EVENT_TIMER3] May 21 11:06:31.322: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=0, DigitBeginFlags=0x1, Rtp Timestamp=0x9A824463, Rtp Expiration=0x0 May 21 11:06:31.582: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=0, Duration=295, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:31.582: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:31.582: htsp_digit_ready(0/2/3): digit = 0 May 21 11:06:31.958: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, DigitBeginFlags=0x1, Rtp Timestamp=0x9A825863, Rtp Expiration=0x0 May 21 11:06:32.190: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, Duration=265, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:32.190: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:32.190: htsp_digit_ready(0/2/3): digit = 4 May 21 11:06:32.522: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=9, DigitBeginFlags=0x1, Rtp Timestamp=0x9A8269E3, Rtp Expiration=0x0 May 21 11:06:32.782: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=9, Duration=295, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:32.782: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:32.782: htsp_digit_ready(0/2/3): digit = 9 May 21 11:06:33.130: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=5, DigitBeginFlags=0x1, Rtp Timestamp=0x9A827CF3, Rtp Expiration=0x0 May 21 11:06:33.350: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=5, Duration=255, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:33.350: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:33.350: htsp_digit_ready(0/2/3): digit = 5 May 21 11:06:33.670: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, DigitBeginFlags=0x1, Rtp Timestamp=0x9A828DD3, Rtp Expiration=0x0 May 21 11:06:33.918: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, Duration=285, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:33.922: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:33.922: htsp_digit_ready(0/2/3): digit = 4 May 21 11:06:34.278: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=6, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82A0E3, Rtp Expiration=0x0 May 21 11:06:34.710: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=6, Duration=275, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:34.710: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:34.710: htsp_digit_ready(0/2/3): digit = 6 May 21 11:06:34.842: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=8, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82B263, Rtp Expiration=0x0 May 21 11:06:35.058: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=8, Duration=255, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:35.062: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:35.062: htsp_digit_ready(0/2/3): digit = 8 May 21 11:06:35.422: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=3, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82C483, Rtp Expiration=0x0 May 21 11:06:35.662: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=3, Duration=275, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:35.662: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:35.662: htsp_digit_ready(0/2/3): digit = 3 May 21 11:06:36.002: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=9, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82D6A3, Rtp Expiration=0x0 May 21 11:06:36.262: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=9, Duration=295, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:36.262: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:36.262: htsp_digit_ready(0/2/3): digit = 9 May 21 11:06:36.610: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82E9B3, Rtp Expiration=0x0 May 21 11:06:36.858: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=4, Duration=285, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:36.862: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:36.862: htsp_digit_ready(0/2/3): digit = 4 May 21 11:06:37.202: //3640/4321998F96F4/CCAPI/cc_api_call_digit_begin: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=2, DigitBeginFlags=0x1, Rtp Timestamp=0x9A82FC23, Rtp Expiration=0x0 May 21 11:06:37.438: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1, Source Call Id=3640, Digit=2, Duration=275, Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF May 21 11:06:37.438: //3640/4321998F96F4/CCAPI/cc_api_call_digit_end: Call Entry(Handoff Depth=0) May 21 11:06:37.438: htsp_digit_ready(0/2/3): digit = 2 May 21 11:06:37.442: //3640/xxxxxxxxxxxx/CCAPI/ccCallReportDigits: (callID=0xE38, digit_event=0x0, enable=FALSE, consume=FALSE) May 21 11:06:37.442: //3640/4321998F96F4/CCAPI/ccCallReportDigits: Enabled=TRUE, Call Id=3640 May 21 11:06:37.442: //3640/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done: (vdbPtr=0x476EA444, callID=0xE38, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE) May 21 11:06:37.442: //3640/4321998F96F4/CCAPI/cc_api_call_report_digits_done: Enabled=TRUE, Disposition=0x0, Interface=0x476EA444, Call Id=3640 May 21 11:06:37.442: //3640/4321998F96F4/CCAPI/cc_api_call_report_digits_done: Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms)) May 21 11:06:37.442: //3640/4321998F96F4/CCAPI/ccCallProceeding: Progress Indication=NULL(0) May 21 11:06:37.446: //3640/4321998F96F4/CCAPI/ccCallSetupRequest: Destination=, Calling IE Present=TRUE, Mode=0, Outgoing Dial-peer=74952, Params=0x488B7758, Progress Indication=ORIGINATING SIDE IS NON ISDN(3) May 21 11:06:37.446: //3640/4321998F96F4/CCAPI/ccCheckClipClir: In: Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) May 21 11:06:37.446: //3640/4321998F96F4/CCAPI/ccCheckClipClir: Out: Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed) May 21 11:06:37.446: //3640/4321998F96F4/CCAPI/ccCallSetupRequest: Destination Pattern=0495......., Called Number=74954683942, Digit Strip=TRUE May 21 11:06:37.446: //3640/4321998F96F4/CCAPI/ccCallSetupRequest: Calling Number=09262264404(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=74954683942(TON=Unknown, NPI=Unknown), Redirect Number=, Display Info= Account Number=, Final Destination Flag=FALSE, Guid=4321998F-263B-11DD-96F4-E9AB1058AC64, Outgoing Dial-peer=74952 May 21 11:06:37.450: //3640/4321998F96F4/CCAPI/cc_api_display_ie_subfields: ccCallSetupRequest: cisco-username= ----- ccCallInfo IE subfields ----- cisco-ani=09262264404 cisco-anitype=0 cisco-aniplan=0 cisco-anipi=0 cisco-anisi=0 dest=74954683942 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 May 21 11:06:37.450: //3640/4321998F96F4/CCAPI/ccIFCallSetupRequestPrivate: Interface=0x476E8490, Interface Type=6, Destination=, Mode=0x0, Call Params(Calling Number=09262264404,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed), Called Number=74954683942(TON=Unknown, NPI=Unknown), Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=74952, Call Count On=FALSE, Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=) May 21 11:06:37.450: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:37.454: :cc_get_feature_vsa malloc success May 21 11:06:37.454: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:37.454: cc_get_feature_vsa count is 2 May 21 11:06:37.454: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa: May 21 11:06:37.454: :FEATURE_VSA attributes are: feature_name:0,feature_time:1198145456,feature_id:1166 May 21 11:06:37.454: //3641/4321998F96F4/CCAPI/ccIFCallSetupRequestPrivate: SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1 May 21 11:06:37.454: //3641/4321998F96F4/CCAPI/ccCallSetContext: Context=0x488B7708 May 21 11:06:37.454: //3640/4321998F96F4/CCAPI/ccSaveDialpeerTag: Outgoing Dial-peer=74952 May 21 11:06:37.454: htsp_timer_stop3 May 21 11:06:37.454: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc May 21 11:06:37.458: htsp_timer - 120000 msechtsp_setup_req May 21 11:06:37.458: htsp_process_event: [0/2/2, FXOLS_ONHOOK, E_HTSP_SETUP_REQ]fxols_onhook_setup May 21 11:06:37.458: [0/2/2] set signal state = 0xC timestamp = 0 May 21 11:06:37.458: htsp_timer - 1300 msec May 21 11:06:37.458: //3641/4321998F96F4/CCAPI/cc_api_call_proceeding: Interface=0x476E8490, Progress Indication=NULL(0) May 21 11:06:38.758: htsp_process_event: [0/2/2, FXOLS_WAIT_DIAL_TONE, E_HTSP_EVENT_TIMER]fxols_wait_dial_timer htsp_dial May 21 11:06:41.003: htsp_process_event: [0/2/2, FXOLS_WAIT_DIAL_DONE, E_DSP_DIALING_DONE]fxols_wait_dial_done htsp_progress May 21 11:06:41.003: //3641/4321998F96F4/CCAPI/cc_api_call_cut_progress: Interface=0x476E8490, Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0 May 21 11:06:41.003: //3641/4321998F96F4/CCAPI/cc_api_call_cut_progress: Call Entry(Responsed=TRUE) May 21 11:06:41.003: //3640/4321998F96F4/CCAPI/ccCallCutProgress: Progress Indication=INBAND(8), Signal Indication=INTERCEPT(2), Cause Value=0 Voice Call Send Alert=FALSE, Call Entry(AlertSent=FALSE) May 21 11:06:41.003: //3640/4321998F96F4/CCAPI/ccCallCutProgress: Call Entry(Responsed=TRUE) May 21 11:06:41.007: //3640/4321998F96F4/CCAPI/ccGenerateToneInfo: Stop Tone On Digit=FALSE, Tone=Null, Tone Direction=Network, Params=0x0, Call Id=3640 May 21 11:06:41.007: //3640/xxxxxxxxxxxx/CCAPI/ccConferenceCreate: (confID=0x48D4792C, callID1=0xE38, callID2=0xE39, tag=0x0) May 21 11:06:41.007: //3640/4321998F96F4/CCAPI/ccConferenceCreate: Conference Id=0x48D4792C, Call Id1=3640, Call Id2=3641, Tag=0x0 May 21 11:06:41.007: htsp_call_bridged invoked May 21 11:06:41.007: //3640/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: Conference Id=0x168, Source Interface=0x476EA444, Source Call Id=3640, Destination Call Id=3641, Disposition=0x0, Tag=0xFFFFFFFF May 21 11:06:41.007: htsp_call_bridged invoked May 21 11:06:41.007: //3641/xxxxxxxxxxxx/CCAPI/cc_api_bridge_done: Conference Id=0x168, Source Interface=0x476E8490, Source Call Id=3641, Destination Call Id=3640, Disposition=0x0, Tag=0xFFFFFFFF May 21 11:06:41.007: //3640/4321998F96F4/CCAPI/cc_generic_bridge_done: Conference Id=0x168, Source Interface=0x476E8490, Source Call Id=3641, Destination Call Id=3640, Disposition=0x0, Tag=0xFFFFFFFF May 21 11:06:41.011: //3640/4321998F96F4/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x168, Destination Call Id=3641) May 21 11:06:41.011: //3641/4321998F96F4/CCAPI/ccConferenceCreate: Call Entry(Conference Id=0x168, Destination Call Id=3640)htsp_progress_notify May 21 11:06:41.011: htsp_process_event: [0/2/2, FXOLS_OFFHOOK, E_DSP_SIG_0100]fxols_offhook_clear May 21 11:06:41.011: htsp_timer_stop2 May 21 11:06:41.015: //3640/4321998F96F4/CCAPI/cc_api_caps_ind: Destination Interface=0x476E8490, Destination Call Id=3641, Source Call Id=3640, Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2, Modem=0x2, Codec Bytes=160, Signal Type=2) May 21 11:06:41.015: //3640/4321998F96F4/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=250(ms), Fax Nom=300(ms)) May 21 11:06:41.015: //3641/4321998F96F4/CCAPI/cc_api_caps_ind: Destination Interface=0x476EA444, Destination Call Id=3640, Source Call Id=3641, Caps(Codec=0x1, Fax Rate=0x2, Vad=0x2, Modem=0x2, Codec Bytes=160, Signal Type=2) May 21 11:06:41.015: //3641/4321998F96F4/CCAPI/cc_api_caps_ind: Caps(Playout Mode=1, Playout Initial=60(ms), Playout Min=40(ms), Playout Max=250(ms), Fax Nom=300(ms)) May 21 11:06:41.015: //3641/4321998F96F4/CCAPI/cc_api_caps_ack: Destination Interface=0x476EA444, Destination Call Id=3640, Source Call Id=3641, 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=1790) May 21 11:06:41.015: //3640/4321998F96F4/CCAPI/cc_api_caps_ack: Destination Interface=0x476E8490, Destination Call Id=3641, Source Call Id=3640, 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=6438) May 21 11:06:41.019: //3640/4321998F96F4/CCAPI/cc_process_notify_bridge_done: Conference Id=0x168, Call Id1=3640, Call Id2=3641 May 21 11:06:41.019: //3641/4321998F96F4/CCAPI/cc_api_voice_mode_event: Call Id=3641 May 21 11:06:41.019: //3641/4321998F96F4/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x488B7708) May 21 11:06:41.019: htsp_process_event: [0/2/2, FXOLS_OFFHOOK, E_HTSP_VOICE_CUT_THROUGH]fxols_proc_voice May 21 11:06:41.023: //3640/4321998F96F4/CCAPI/cc_api_voice_mode_event: Call Id=3640 May 21 11:06:41.023: //3640/4321998F96F4/CCAPI/cc_api_voice_mode_event: Call Entry(Context=0x488AE658) May 21 11:06:41.023: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_VOICE_CUT_THROUGH]fxols_proc_voice May 21 11:06:43.223: //3641/4321998F96F4/CCAPI/cc_api_call_feature: Feature Type=2, Interface=0x476E8490, Call Id=3641 May 21 11:06:43.223: //3640/4321998F96F4/CCAPI/ccCallFeature: Feature Type=2, Call Id=3640htsp_call_feature:feature 2 May 21 11:06:43.231: //3640/4321998F96F4/CCAPI/cc_api_call_feature: Feature Type=13, Interface=0x476EA444, Call Id=3640 May 21 11:06:43.263: //3641/4321998F96F4/CCAPI/cc_api_modify_media_ind: IFType=0x6, Source Call Id=0xE39, Destination Call Id=0xE38, Codec=1, Codec Bytes=160 May 21 11:06:43.271: //3640/4321998F96F4/CCAPI/cc_api_modify_media_ind: IFType=0x6, Source Call Id=0xE38, Destination Call Id=0xE39, Codec=1, Codec Bytes=160 dodge#sh call act dodge#sh call active voice Telephony call-legs: 2 SIP call-legs: 0 H323 call-legs: 0 Call agent controlled call-legs: 0 SCCP call-legs: 0 Multicast call-legs: 0 Media call-legs: 0 Total call-legs: 2 GENERIC: SetupTime=1165938120 ms Index=1 PeerAddress=09262264404 PeerSubAddress= PeerId=79100 PeerIfIndex=75 LogicalIfIndex=14 ConnectTime=0 ms CallDuration=00:00:00 sec CallState=3 CallOrigin=2 ChargedUnits=0 InfoType=speech TransmitPackets=4846 TransmitBytes=801681 ReceivePackets=4759 ReceiveBytes=760813 TELE: ConnectionId=[0x4321998F 0x263B11DD 0x96F4E9AB 0x1058AC64] IncomingConnectionId=[0x4321998F 0x263B11DD 0x96F4E9AB 0x1058AC64] CallID=3640 Port=0/2/3 (3640) BearerChannel=0/2/3 TxDuration=94685 ms VoiceTxDuration=94685 ms FaxTxDuration=0 ms CoderTypeRate=g711ulaw NoiseLevel=-66 ACOMLevel=40 OutSignalLevel=-15 InSignalLevel=-55 InfoActivity=1 ERLLevel=15 EchoCancellerMaxReflector=3 SessionTarget= ImgPages=0 CallerName= CallerIDBlocked=False LongDurationCallDetected=no LongDurCallTimeStamp= LongDurCallDuration= OriginalCallingNumber=89262264404 OriginalCallingOctet=0x0 OriginalCalledNumber= OriginalCalledOctet=0x80 OriginalRedirectCalledNumber= OriginalRedirectCalledOctet=0x0 TranslatedCallingNumber=09262264404 TranslatedCallingOctet=0x0 TranslatedCalledNumber= TranslatedCalledOctet=0x80 TranslatedRedirectCalledNumber= TranslatedRedirectCalledOctet=0x0 GwCollectedCalledNumber=04954683942 GwReceivedCallingNumber=89262264404 GwReceivedCallingOctet3=0x0 GwReceivedCallingOctet3a=0x0 DSPIdentifier=0/1:1 GENERIC: SetupTime=1165949360 ms Index=1 PeerAddress=74954683942 PeerSubAddress= PeerId=74952 PeerIfIndex=71 LogicalIfIndex=13 ConnectTime=0 ms CallDuration=00:00:00 sec CallState=2 CallOrigin=1 ChargedUnits=0 InfoType=speech TransmitPackets=4942 TransmitBytes=829629 ReceivePackets=5028 ReceiveBytes=792033 TELE: ConnectionId=[0x4321998F 0x263B11DD 0x96F4E9AB 0x1058AC64] IncomingConnectionId=[0x4321998F 0x263B11DD 0x96F4E9AB 0x1058AC64] CallID=3641 Port=0/2/2 (3641) BearerChannel=0/2/2 TxDuration=98340 ms VoiceTxDuration=98340 ms FaxTxDuration=0 ms CoderTypeRate=g711ulaw NoiseLevel=-68 ACOMLevel=19 OutSignalLevel=-52 InSignalLevel=-69 InfoActivity=1 ERLLevel=19 EchoCancellerMaxReflector=3 SessionTarget= ImgPages=0 CallerName= CallerIDBlocked=False LongDurationCallDetected=no LongDurCallTimeStamp= LongDurCallDuration= OriginalCallingNumber=09262264404 OriginalCallingOctet=0x0 OriginalCalledNumber= OriginalCalledOctet=0x80 OriginalRedirectCalledNumber= OriginalRedirectCalledOctet=0x0 TranslatedCallingNumber=09262264404 TranslatedCallingOctet=0x0 TranslatedCalledNumber=74954683942 TranslatedCalledOctet=0x80 TranslatedRedirectCalledNumber= TranslatedRedirectCalledOctet=0x0 GwCollectedCalledNumber=04954683942 GwOutpulsedCalledNumber=84954683942 GwOutpulsedCalledOctet3=0x80 GwReceivedCallingNumber=89262264404 GwReceivedCallingOctet3=0x0 GwReceivedCallingOctet3a=0x0 GwOutpulsedCallingNumber=09262264404 GwOutpulsedCallingOctet3=0x0 GwOutpulsedCallingOctet3a=0x0 DSPIdentifier=0/1:2 Telephony call-legs: 2 SIP call-legs: 0 H323 call-legs: 0 Call agent controlled call-legs: 0 SCCP call-legs: 0 Multicast call-legs: 0 Media call-legs: 0 Total call-legs: 2 dodge# May 21 11:08:37.460: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_EVENT_TIMER]fxols_disc_confirm May 21 11:08:37.460: htsp_timer_stop May 21 11:08:37.460: htsp_timer_stop2 May 21 11:08:37.460: htsp_timer_stop3 May 21 11:08:37.460: //3640/4321998F96F4/CCAPI/cc_api_call_disconnected: Cause Value=17, Interface=0x476EA444, Call Id=3640 May 21 11:08:37.460: //3640/4321998F96F4/CCAPI/cc_api_call_disconnected: Call Entry(Responsed=TRUE, Cause Value=17, Retry Count=0) May 21 11:08:37.460: //3640/4321998F96F4/CCAPI/ccConferenceDestroy: Conference Id=0x168, Tag=0x0 May 21 11:08:37.460: //3640/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: Conference Id=0x168, Source Interface=0x476EA444, Source Call Id=3640, Destination Call Id=3641, Disposition=0x0, Tag=0x0 May 21 11:08:37.460: //3641/xxxxxxxxxxxx/CCAPI/cc_api_bridge_drop_done: Conference Id=0x168, Source Interface=0x476E8490, Source Call Id=3641, Destination Call Id=3640, Disposition=0x0, Tag=0x0 May 21 11:08:37.460: //3640/4321998F96F4/CCAPI/cc_generic_bridge_done: Conference Id=0x168, Source Interface=0x476E8490, Source Call Id=3641, Destination Call Id=3640, Disposition=0x0, Tag=0x0 May 21 11:08:37.464: //3641/4321998F96F4/CCAPI/ccCallDisconnect: Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0) May 21 11:08:37.464: //3641/4321998F96F4/CCAPI/ccCallDisconnect: Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17) May 21 11:08:37.464: //3641/4321998F96F4/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null May 21 11:08:37.468: htsp_timer_stop3 May 21 11:08:37.468: //3640/4321998F96F4/CCAPI/ccCallDisconnect: Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=17) May 21 11:08:37.472: //3640/4321998F96F4/CCAPI/ccCallDisconnect: Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17) May 21 11:08:37.472: //3640/4321998F96F4/CCAPI/cc_api_get_transfer_info: Transfer Number Is Null May 21 11:08:37.476: htsp_process_event: [0/2/2, FXOLS_OFFHOOK, E_HTSP_RELEASE_REQ]fxols_offhook_release May 21 11:08:37.476: htsp_timer_stop May 21 11:08:37.476: htsp_timer_stop2 May 21 11:08:37.476: htsp_timer_stop3 May 21 11:08:37.476: [0/2/2] set signal state = 0x4 timestamp = 0 May 21 11:08:37.476: htsp_timer - 2000 msec May 21 11:08:37.480: htsp_process_event: [0/2/3, FXOLS_PROCEEDING, E_HTSP_RELEASE_REQ]fxols_offhook_release May 21 11:08:37.480: htsp_timer_stop May 21 11:08:37.480: htsp_timer_stop2 May 21 11:08:37.480: htsp_timer_stop3 May 21 11:08:37.480: [0/2/3] set signal state = 0x4 timestamp = 0 May 21 11:08:37.480: htsp_timer - 2000 msec May 21 11:08:37.484: //3641/4321998F96F4/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x476E8490, Tag=0x0, Call Id=3641, Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0) May 21 11:08:37.484: //3641/4321998F96F4/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent May 21 11:08:37.484: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: May 21 11:08:37.484: :cc_free_feature_vsa freeing 476A3FA8 May 21 11:08:37.484: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: May 21 11:08:37.484: vsacount in free is 1 May 21 11:08:37.488: //3640/4321998F96F4/CCAPI/cc_api_call_disconnect_done: Disposition=0, Interface=0x476EA444, Tag=0x0, Call Id=3640, Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0) May 21 11:08:37.488: //3640/4321998F96F4/CCAPI/cc_api_call_disconnect_done: Call Disconnect Event Sent May 21 11:08:37.488: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: May 21 11:08:37.488: :cc_free_feature_vsa freeing 476A3ED0 May 21 11:08:37.488: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa: May 21 11:08:37.488: vsacount in free is 0 May 21 11:08:37.752: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT, E_DSP_SIG_0110] May 21 11:08:39.476: htsp_process_event: [0/2/2, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout May 21 11:08:39.476: htsp_process_event: [0/2/2, FXOLS_ONHOOK, E_DSP_SIG_0100] May 21 11:08:39.480: htsp_process_event: [0/2/3, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout May 21 11:08:39.480: htsp_process_event: [0/2/3, FXOLS_ONHOOK, E_DSP_SIG_0100]