voipgw1#show log Syslog logging: enabled (0 messages dropped, 37 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator. Console logging: disabled Monitor logging: disabled Buffer logging: level debugging, 2295 messages logged, xml disabled, filtering disabled Exception Logging: size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. Trap logging: level debugging, 5681 message lines logged Logging to 10.0.7.139 (udp port 514, audit disabled, link up), 5412 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging to 10.0.8.12 (udp port 514, audit disabled, link up), 5412 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging Source-Interface: VRF Name: Loopback0 Log Buffer (99999 bytes): Mar 1 18:02:08.320 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=24065 systime=601889613 Mar 1 18:02:08.320 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:02:08.320 UTC: htsp_timer - 125 msec Mar 1 18:02:08.448 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:02:08.448 UTC: htsp_timer - 10000 msec Mar 1 18:02:08.448 UTC: htsp_timer3 - 5600 msec Mar 1 18:02:08.448 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:02:08.448 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:02:08.448 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:02:10.408 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=26154 systime=601889822 Mar 1 18:02:10.408 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:02:10.408 UTC: fxols_ringing_not Mar 1 18:02:10.408 UTC: htsp_timer_stop Mar 1 18:02:10.408 UTC: htsp_timer - 10000 msec Mar 1 18:02:14.048 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:02:14.048 UTC: htsp_timer_stop3 Mar 1 18:02:14.320 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=30062 systime=601890213 Mar 1 18:02:14.320 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:02:16.409 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=32149 systime=601890422 Mar 1 18:02:16.409 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:02:16.409 UTC: fxols_ringing_not Mar 1 18:02:16.409 UTC: htsp_timer_stop Mar 1 18:02:16.409 UTC: htsp_timer_stop3 Mar 1 18:02:16.409 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:02:16.409 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:02:16.409 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:02 orig called= Mar 1 18:02:16.409 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:02:16.409 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:02:16.409 UTC: fxols_wait_setup_ack: Mar 1 18:02:16.409 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:02:16.413 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:02:16.413 UTC: htsp_timer - 120000 msec Mar 1 18:02:16.489 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:02:16.489 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:02:16.685 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=32426 systime=601890449 Mar 1 18:02:16.685 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:02:16.685 UTC: htsp_timer_stop2 Mar 1 18:02:20.821 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=36566 systime=601890863 Mar 1 18:02:20.821 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:02:20.821 UTC: htsp_timer2 - 350 msec Mar 1 18:02:21.173 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:02:21.173 UTC: htsp_timer_stop Mar 1 18:02:21.173 UTC: htsp_timer_stop2 Mar 1 18:02:21.173 UTC: htsp_timer_stop3 Mar 1 18:02:21.173 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:02:21.173 UTC: htsp_timer_stop Mar 1 18:02:21.173 UTC: htsp_timer_stop2 Mar 1 18:02:21.173 UTC: htsp_timer_stop3 Mar 1 18:02:21.173 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:02:21.173 UTC: htsp_timer - 2000 msec Mar 1 18:02:21.657 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=37397 systime=601890946 Mar 1 18:02:21.657 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:02:23.173 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:02:23.173 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=37397 systime=601891098 Mar 1 18:02:23.173 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:11:29.733 UTC: %SYS-5-CONFIG_I: Configured from console by dlog007 on vty1 (10.82.116.56) Mar 1 18:11:40.685 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=6595 systime=601946848 Mar 1 18:11:40.685 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:11:40.685 UTC: htsp_timer - 125 msec Mar 1 18:11:40.813 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:11:40.813 UTC: htsp_timer - 10000 msec Mar 1 18:11:40.813 UTC: htsp_timer3 - 5600 msec Mar 1 18:11:40.813 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:11:40.813 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:11:40.813 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:11:42.773 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=8683 systime=601947056 Mar 1 18:11:42.773 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:11:42.773 UTC: fxols_ringing_not Mar 1 18:11:42.773 UTC: htsp_timer_stop Mar 1 18:11:42.773 UTC: htsp_timer - 10000 msec Mar 1 18:11:46.413 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:11:46.413 UTC: htsp_timer_stop3 Mar 1 18:11:46.705 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=12615 systime=601947450 Mar 1 18:11:46.705 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:11:48.765 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=14678 systime=601947656 Mar 1 18:11:48.765 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:11:48.765 UTC: fxols_ringing_not Mar 1 18:11:48.765 UTC: htsp_timer_stop Mar 1 18:11:48.765 UTC: htsp_timer_stop3 Mar 1 18:11:48.765 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:11:48.765 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:11:48.765 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:11 orig called= Mar 1 18:11:48.769 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:11:48.769 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:11:48.769 UTC: fxols_wait_setup_ack: Mar 1 18:11:48.769 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:11:48.769 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:11:48.773 UTC: htsp_timer - 120000 msec Mar 1 18:11:48.849 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:11:48.849 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:11:49.041 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=14952 systime=601947683 Mar 1 18:11:49.041 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:11:49.041 UTC: htsp_timer_stop2 Mar 1 18:11:54.221 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=20132 systime=601948201 Mar 1 18:11:54.221 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:11:54.221 UTC: htsp_timer2 - 350 msec Mar 1 18:11:54.573 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:11:54.573 UTC: htsp_timer_stop Mar 1 18:11:54.573 UTC: htsp_timer_stop2 Mar 1 18:11:54.573 UTC: htsp_timer_stop3 Mar 1 18:11:54.573 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:11:54.573 UTC: htsp_timer_stop Mar 1 18:11:54.573 UTC: htsp_timer_stop2 Mar 1 18:11:54.573 UTC: htsp_timer_stop3 Mar 1 18:11:54.573 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:11:54.573 UTC: htsp_timer - 2000 msec Mar 1 18:11:55.049 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=20958 systime=601948284 Mar 1 18:11:55.049 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:11:56.573 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:11:56.573 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=20958 systime=601948436 Mar 1 18:11:56.573 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:18:15.776 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=8464 systime=601986356 Mar 1 18:18:15.776 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:18:15.776 UTC: htsp_timer - 125 msec Mar 1 18:18:15.904 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:18:15.904 UTC: htsp_timer - 10000 msec Mar 1 18:18:15.904 UTC: htsp_timer3 - 5600 msec Mar 1 18:18:15.904 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:18:15.904 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:18:15.904 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:18:17.864 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=10553 systime=601986564 Mar 1 18:18:17.864 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:18:17.864 UTC: fxols_ringing_not Mar 1 18:18:17.864 UTC: htsp_timer_stop Mar 1 18:18:17.864 UTC: htsp_timer - 10000 msec Mar 1 18:18:21.504 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:18:21.504 UTC: htsp_timer_stop3 Mar 1 18:18:21.768 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=14460 systime=601986955 Mar 1 18:18:21.768 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:18:23.857 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=16547 systime=601987164 Mar 1 18:18:23.857 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:18:23.857 UTC: fxols_ringing_not Mar 1 18:18:23.857 UTC: htsp_timer_stop Mar 1 18:18:23.857 UTC: htsp_timer_stop3 Mar 1 18:18:23.857 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:18:23.857 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:18:23.857 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:18 orig called= Mar 1 18:18:23.861 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:18:23.861 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:18:23.861 UTC: fxols_wait_setup_ack: Mar 1 18:18:23.861 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:18:23.865 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:18:23.865 UTC: htsp_timer - 120000 msec Mar 1 18:18:23.941 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:18:23.941 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:18:24.137 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=16822 systime=601987191 Mar 1 18:18:24.137 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:18:24.137 UTC: htsp_timer_stop2 Mar 1 18:18:36.917 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=29607 systime=601988470 Mar 1 18:18:36.917 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:18:36.917 UTC: htsp_timer2 - 350 msec Mar 1 18:18:37.269 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:18:37.269 UTC: htsp_timer_stop Mar 1 18:18:37.269 UTC: htsp_timer_stop2 Mar 1 18:18:37.269 UTC: htsp_timer_stop3 Mar 1 18:18:37.269 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:18:37.269 UTC: htsp_timer_stop Mar 1 18:18:37.269 UTC: htsp_timer_stop2 Mar 1 18:18:37.269 UTC: htsp_timer_stop3 Mar 1 18:18:37.269 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:18:37.269 UTC: htsp_timer - 2000 msec Mar 1 18:18:37.745 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=30435 systime=601988552 Mar 1 18:18:37.745 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:18:39.269 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:18:39.269 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=30435 systime=601988705 Mar 1 18:18:39.269 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:23:54.542 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=19546 systime=602020231 Mar 1 18:23:54.542 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:23:54.542 UTC: htsp_timer - 125 msec Mar 1 18:23:54.670 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:23:54.670 UTC: htsp_timer - 10000 msec Mar 1 18:23:54.670 UTC: htsp_timer3 - 5600 msec Mar 1 18:23:54.670 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:23:54.670 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:23:54.670 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:23:56.626 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=21633 systime=602020440 Mar 1 18:23:56.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:23:56.626 UTC: fxols_ringing_not Mar 1 18:23:56.626 UTC: htsp_timer_stop Mar 1 18:23:56.626 UTC: htsp_timer - 10000 msec Mar 1 18:24:00.270 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:24:00.270 UTC: htsp_timer_stop3 Mar 1 18:24:00.538 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=25540 systime=602020831 Mar 1 18:24:00.538 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:24:02.626 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=27629 systime=602021040 Mar 1 18:24:02.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:24:02.626 UTC: fxols_ringing_not Mar 1 18:24:02.626 UTC: htsp_timer_stop Mar 1 18:24:02.626 UTC: htsp_timer_stop3 Mar 1 18:24:02.626 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:24:02.626 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:24:02.626 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:24 orig called= Mar 1 18:24:02.630 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:24:02.630 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:24:02.630 UTC: fxols_wait_setup_ack: Mar 1 18:24:02.630 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:24:02.634 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:24:02.634 UTC: htsp_timer - 120000 msec Mar 1 18:24:02.706 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:24:02.706 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:24:02.902 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=27907 systime=602021067 Mar 1 18:24:02.902 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:24:02.902 UTC: htsp_timer_stop2 Mar 1 18:24:15.779 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=40784 systime=602022355 Mar 1 18:24:15.779 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:24:15.779 UTC: htsp_timer2 - 350 msec Mar 1 18:24:16.131 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:24:16.131 UTC: htsp_timer_stop Mar 1 18:24:16.131 UTC: htsp_timer_stop2 Mar 1 18:24:16.131 UTC: htsp_timer_stop3 Mar 1 18:24:16.131 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:24:16.131 UTC: htsp_timer_stop Mar 1 18:24:16.131 UTC: htsp_timer_stop2 Mar 1 18:24:16.131 UTC: htsp_timer_stop3 Mar 1 18:24:16.131 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:24:16.131 UTC: htsp_timer - 2000 msec Mar 1 18:24:16.599 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=41606 systime=602022437 Mar 1 18:24:16.599 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:24:18.131 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:24:18.131 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=41606 systime=602022590 Mar 1 18:24:18.131 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:29:41.628 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=38945 systime=602054939 Mar 1 18:29:41.628 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:29:41.628 UTC: htsp_timer - 125 msec Mar 1 18:29:41.756 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:29:41.756 UTC: htsp_timer - 10000 msec Mar 1 18:29:41.756 UTC: htsp_timer3 - 5600 msec Mar 1 18:29:41.756 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:29:41.756 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:29:41.756 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:29:43.716 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=41034 systime=602055148 Mar 1 18:29:43.716 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:29:43.716 UTC: fxols_ringing_not Mar 1 18:29:43.716 UTC: htsp_timer_stop Mar 1 18:29:43.716 UTC: htsp_timer - 10000 msec Mar 1 18:29:47.356 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:29:47.356 UTC: htsp_timer_stop3 Mar 1 18:29:47.620 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=44941 systime=602055538 Mar 1 18:29:47.620 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:29:49.708 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=47028 systime=602055747 Mar 1 18:29:49.708 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:29:49.708 UTC: fxols_ringing_not Mar 1 18:29:49.708 UTC: htsp_timer_stop Mar 1 18:29:49.708 UTC: htsp_timer_stop3 Mar 1 18:29:49.708 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:29:49.708 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:29:49.708 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:29 orig called= Mar 1 18:29:49.712 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:29:49.712 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:29:49.712 UTC: fxols_wait_setup_ack: Mar 1 18:29:49.712 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:29:49.716 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:29:49.716 UTC: htsp_timer - 120000 msec Mar 1 18:29:49.788 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:29:49.788 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:29:49.984 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=47304 systime=602055774 Mar 1 18:29:49.984 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:29:49.984 UTC: htsp_timer_stop2 Mar 1 18:30:01.273 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=58594 systime=602056903 Mar 1 18:30:01.273 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:30:01.273 UTC: htsp_timer2 - 350 msec Mar 1 18:30:01.625 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:30:01.625 UTC: htsp_timer_stop Mar 1 18:30:01.625 UTC: htsp_timer_stop2 Mar 1 18:30:01.625 UTC: htsp_timer_stop3 Mar 1 18:30:01.625 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:30:01.625 UTC: htsp_timer_stop Mar 1 18:30:01.625 UTC: htsp_timer_stop2 Mar 1 18:30:01.625 UTC: htsp_timer_stop3 Mar 1 18:30:01.625 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:30:01.625 UTC: htsp_timer - 2000 msec Mar 1 18:30:02.105 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=59422 systime=602056986 Mar 1 18:30:02.105 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:30:03.625 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:30:03.625 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=59422 systime=602057138 Mar 1 18:30:03.625 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:35:11.898 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=41532 systime=602087965 Mar 1 18:35:11.898 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:35:11.898 UTC: htsp_timer - 125 msec Mar 1 18:35:12.026 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:35:12.026 UTC: htsp_timer - 10000 msec Mar 1 18:35:12.026 UTC: htsp_timer3 - 5600 msec Mar 1 18:35:12.026 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:35:12.026 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:35:12.026 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:35:13.986 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=43619 systime=602088174 Mar 1 18:35:13.986 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:35:13.986 UTC: fxols_ringing_not Mar 1 18:35:13.986 UTC: htsp_timer_stop Mar 1 18:35:13.986 UTC: htsp_timer - 10000 msec Mar 1 18:35:17.626 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:35:17.626 UTC: htsp_timer_stop3 Mar 1 18:35:17.918 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=47552 systime=602088567 Mar 1 18:35:17.918 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:35:19.982 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=49615 systime=602088773 Mar 1 18:35:19.982 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:35:19.982 UTC: fxols_ringing_not Mar 1 18:35:19.982 UTC: htsp_timer_stop Mar 1 18:35:19.982 UTC: htsp_timer_stop3 Mar 1 18:35:19.982 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:35:19.982 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:35:19.982 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:35 orig called= Mar 1 18:35:19.982 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:35:19.986 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:35:19.986 UTC: fxols_wait_setup_ack: Mar 1 18:35:19.986 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:35:19.986 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:35:19.986 UTC: htsp_timer - 120000 msec Mar 1 18:35:20.062 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:35:20.062 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:35:20.258 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=49892 systime=602088801 Mar 1 18:35:20.258 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:35:20.258 UTC: htsp_timer_stop2 Mar 1 18:35:30.842 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=60477 systime=602089859 Mar 1 18:35:30.842 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:35:30.842 UTC: htsp_timer2 - 350 msec Mar 1 18:35:31.194 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:35:31.194 UTC: htsp_timer_stop Mar 1 18:35:31.194 UTC: htsp_timer_stop2 Mar 1 18:35:31.194 UTC: htsp_timer_stop3 Mar 1 18:35:31.194 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:35:31.194 UTC: htsp_timer_stop Mar 1 18:35:31.194 UTC: htsp_timer_stop2 Mar 1 18:35:31.194 UTC: htsp_timer_stop3 Mar 1 18:35:31.194 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:35:31.194 UTC: htsp_timer - 2000 msec Mar 1 18:35:31.650 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=61287 systime=602089940 Mar 1 18:35:31.650 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:35:33.194 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:35:33.194 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=61287 systime=602090094 Mar 1 18:35:33.194 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] Mar 1 18:39:41.837 UTC: %SYS-5-CONFIG_I: Configured from console by hebi001 on vty0 (10.82.116.50) Mar 1 18:40:39.451 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=41400 systime=602120719 Mar 1 18:40:39.451 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:40:39.451 UTC: htsp_timer - 125 msec Mar 1 18:40:39.579 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:40:39.579 UTC: htsp_timer - 10000 msec Mar 1 18:40:39.579 UTC: htsp_timer3 - 5600 msec Mar 1 18:40:39.579 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:40:39.579 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:40:39.579 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:40:41.511 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=43462 systime=602120925 Mar 1 18:40:41.511 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:40:41.511 UTC: fxols_ringing_not Mar 1 18:40:41.511 UTC: htsp_timer_stop Mar 1 18:40:41.511 UTC: htsp_timer - 10000 msec Mar 1 18:40:45.179 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:40:45.179 UTC: htsp_timer_stop3 Mar 1 18:40:45.447 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=47395 systime=602121319 Mar 1 18:40:45.447 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:40:47.507 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=49458 systime=602121525 Mar 1 18:40:47.507 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:40:47.507 UTC: fxols_ringing_not Mar 1 18:40:47.507 UTC: htsp_timer_stop Mar 1 18:40:47.507 UTC: htsp_timer_stop3 Mar 1 18:40:47.511 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:40:47.511 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:40:47.511 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:40 orig called= Mar 1 18:40:47.511 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:40:47.511 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:40:47.511 UTC: fxols_wait_setup_ack: Mar 1 18:40:47.511 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:40:47.515 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:40:47.515 UTC: htsp_timer - 120000 msec Mar 1 18:40:47.587 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:40:47.587 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:40:47.787 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=49734 systime=602121553 Mar 1 18:40:47.787 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:40:47.787 UTC: htsp_timer_stop2 Mar 1 18:41:03.400 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=65351 systime=602123114 Mar 1 18:41:03.400 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:41:03.400 UTC: htsp_timer2 - 350 msec Mar 1 18:41:03.752 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:41:03.752 UTC: htsp_timer_stop Mar 1 18:41:03.752 UTC: htsp_timer_stop2 Mar 1 18:41:03.752 UTC: htsp_timer_stop3 Mar 1 18:41:03.752 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:41:03.752 UTC: htsp_timer_stop Mar 1 18:41:03.752 UTC: htsp_timer_stop2 Mar 1 18:41:03.752 UTC: htsp_timer_stop3 Mar 1 18:41:03.752 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:41:03.752 UTC: htsp_timer - 2000 msec Mar 1 18:41:04.212 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=626 systime=602123195 Mar 1 18:41:04.212 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:41:05.752 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:41:05.752 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=626 systime=602123349 Mar 1 18:41:05.752 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] voipgw1# voipgw1# voipgw1# voipgw1# voipgw1#clear log Clear logging buffer [confirm] q70007voipgw1#un all All possible debugging has been turned off voipgw1# voipgw1# voipgw1#debug vpm signal Voice Port Module signaling debugging is enabled voipgw1# voipgw1#show log Syslog logging: enabled (0 messages dropped, 37 messages rate-limited, 0 flushes, 0 overruns, xml disabled, filtering disabled) No Active Message Discriminator. No Inactive Message Discriminator. Console logging: disabled Monitor logging: disabled Buffer logging: level debugging, 2361 messages logged, xml disabled, filtering disabled Exception Logging: size (4096 bytes) Count and timestamp logging messages: disabled Persistent logging: disabled No active filter modules. Trap logging: level debugging, 5736 message lines logged Logging to 10.0.7.139 (udp port 514, audit disabled, link up), 5467 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging to 10.0.8.12 (udp port 514, audit disabled, link up), 5467 message lines logged, 0 message lines rate-limited, 0 message lines dropped-by-MD, xml disabled, sequence number disabled filtering disabled Logging Source-Interface: VRF Name: Loopback0 Log Buffer (99999 bytes): Mar 1 18:48:37.809 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=60999 systime=602168554 Mar 1 18:48:37.809 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing Mar 1 18:48:37.809 UTC: htsp_timer - 125 msec Mar 1 18:48:37.937 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer Mar 1 18:48:37.937 UTC: htsp_timer - 10000 msec Mar 1 18:48:37.937 UTC: htsp_timer3 - 5600 msec Mar 1 18:48:37.937 UTC: [0/2/0] htsp_start_caller_id_rx:BELLCORE Mar 1 18:48:37.937 UTC: htsp_start_caller_id_rx create dsp_stream_manager Mar 1 18:48:37.937 UTC: [0/2/0] htsp_dsm_create_success returns 1 Mar 1 18:48:39.897 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=63087 systime=602168762 Mar 1 18:48:39.897 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:48:39.897 UTC: fxols_ringing_not Mar 1 18:48:39.897 UTC: htsp_timer_stop Mar 1 18:48:39.897 UTC: htsp_timer - 10000 msec Mar 1 18:48:43.537 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_HTSP_EVENT_TIMER3]fxols_snoop_clid_stop Mar 1 18:48:43.537 UTC: htsp_timer_stop3 Mar 1 18:48:43.801 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x0 timestamp=1458 systime=602169153 Mar 1 18:48:43.801 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0000] Mar 1 18:48:45.889 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x4 timestamp=3544 systime=602169362 Mar 1 18:48:45.889 UTC: htsp_process_event: [0/2/0, FXOLS_RINGING, E_DSP_SIG_0100] Mar 1 18:48:45.889 UTC: fxols_ringing_not Mar 1 18:48:45.889 UTC: htsp_timer_stop Mar 1 18:48:45.889 UTC: htsp_timer_stop3 Mar 1 18:48:45.889 UTC: [0/2/0] htsp_stop_caller_id_rx. message length 0htsp_setup_ind Mar 1 18:48:45.889 UTC: [0/2/0] get_fxo_caller_id:Caller ID receive failed. parseCallerIDString:no data. Mar 1 18:48:45.889 UTC: [0/2/0] get_local_station_id calling num= calling name= calling time=03/01 18:48 orig called= Mar 1 18:48:45.893 UTC: [0/2/0] htsp_dsm_close_done Mar 1 18:48:45.893 UTC: htsp_process_event: [0/2/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK] Mar 1 18:48:45.893 UTC: fxols_wait_setup_ack: Mar 1 18:48:45.893 UTC: [0/2/0] set signal state = 0xC timestamp = 0fxols_check_auto_call Mar 1 18:48:45.897 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc Mar 1 18:48:45.897 UTC: htsp_timer - 120000 msec Mar 1 18:48:45.969 UTC: htsp_pre_connect_disconnect, cdb = C0D948C0 cause = 26 Mar 1 18:48:45.969 UTC: htsp_process_event: [0/2/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC] Mar 1 18:48:46.169 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=3821 systime=602169390 Mar 1 18:48:46.169 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_0110] Mar 1 18:48:46.169 UTC: htsp_timer_stop2 Mar 1 18:49:08.958 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0xC timestamp=26609 systime=602171668 Mar 1 18:49:08.958 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_DSP_SIG_1100]fxols_offhook_disc Mar 1 18:49:08.958 UTC: htsp_timer2 - 350 msec Mar 1 18:49:09.310 UTC: htsp_process_event: [0/2/0, FXOLS_OFFHOOK, E_HTSP_EVENT_TIMER2]fxols_disc_confirm Mar 1 18:49:09.310 UTC: htsp_timer_stop Mar 1 18:49:09.310 UTC: htsp_timer_stop2 Mar 1 18:49:09.310 UTC: htsp_timer_stop3 Mar 1 18:49:09.310 UTC: htsp_process_event: [0/2/0, FXOLS_REMOTE_RELEASE, E_HTSP_RELEASE_REQ]fxols_offhook_release Mar 1 18:49:09.310 UTC: htsp_timer_stop Mar 1 18:49:09.310 UTC: htsp_timer_stop2 Mar 1 18:49:09.310 UTC: htsp_timer_stop3 Mar 1 18:49:09.310 UTC: [0/2/0] set signal state = 0x4 timestamp = 0 Mar 1 18:49:09.310 UTC: htsp_timer - 2000 msec Mar 1 18:49:09.782 UTC: htsp_dsp_message: SEND_SIG_STATUS: state=0x6 timestamp=27438 systime=602171751 Mar 1 18:49:09.782 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110] Mar 1 18:49:11.310 UTC: htsp_process_event: [0/2/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout Mar 1 18:49:11.310 UTC: htsp_dsp_message: RESP_SIG_STATUS: state=0x4 timestamp=27438 systime=602171904 Mar 1 18:49:11.310 UTC: htsp_process_event: [0/2/0, FXOLS_ONHOOK, E_DSP_SIG_0100] voipgw1# voipgw1# voipgw1#