*Feb 26 02:05:50.599: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_DSP_SIG_0000]fxols_onhook_ringing
*Feb 26 02:05:50.599: htsp_timer3 - 5600 msec
*Feb 26 02:05:50.599: [0/0/0] htsp_start_caller_id_rx:BELLCORE
*Feb 26 02:05:50.599: htsp_timer - 125 msec
*Feb 26 02:05:50.599: [0/0/0] htsp_dsm_create_success  returns 1
*Feb 26 02:05:50.727: htsp_process_event: [0/0/0, FXOLS_WAIT_RING_MIN, E_HTSP_EVENT_TIMER]fxols_wait_ring_min_timer
*Feb 26 02:05:50.727: htsp_timer - 10000 msec
*Feb 26 02:05:52.703: htsp_process_event: [0/0/0, FXOLS_RINGING, E_DSP_SIG_0100]
*Feb 26 02:05:52.703: fxols_ringing_not
*Feb 26 02:05:52.707: htsp_timer_stop
*Feb 26 02:05:52.707: htsp_timer - 10000 msec
*Feb 26 02:05:53.679: [0/0/0] htsp_dsm_feature_notify_cb  returns 2 id=DSM_FEATURE_SM_CALLERID_RX
*Feb 26 02:05:53.679: htsp_process_event: [0/0/0, FXOLS_RINGING, E_HTSP_CALLERID_RX_DONE]
*Feb 26 02:05:53.679: htsp_timer_stop
*Feb 26 02:05:53.679: [0/0/0] htsp_stop_caller_id_rx. message length 33htsp_setup_ind
*Feb 26 02:05:53.679: [0/0/0] get_fxo_caller_id:Caller ID received. Message type=128 length=33 checksum=92
*Feb 26 02:05:53.679: [0/0/0] Caller ID String 80 1E 01 08 30 32 32 35 31 37 32 31 02 05 34 31 38 39 36 07 0B 4E 69 63 6B 20 48 6F 62 73 6F 6E 92
*Feb 26 02:05:53.683: [0/0/0] get_fxo_caller_id calling num=41896 calling name=Nick Hobson calling time=02/25 17:21
*Feb 26 02:05:53.683: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=41896, Called Number=, Voice-Interface=0x458A52B4,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 26 02:05:53.683: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
*Feb 26 02:05:53.683: fxols_callerid_done: call being answered
*Feb 26 02:05:53.687: htsp_process_event: [0/0/0, FXOLS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]
*Feb 26 02:05:53.687: fxols_wait_setup_ack:
*Feb 26 02:05:53.687: [0/0/0] set signal state = 0xC timestamp = 0fxols_check_auto_call
*Feb 26 02:05:53.687: [0/0/0] htsp_dsm_close_done
*Feb 26 02:05:53.691: //-1/31CA5E0A8008/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=41851, Peer Info Type=DIALPEER_INFO_SPEECH
*Feb 26 02:05:53.691: //-1/31CA5E0A8008/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=41851
*Feb 26 02:05:53.691: //-1/31CA5E0A8008/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
*Feb 26 02:05:53.691: //-1/31CA5E0A8008/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=41851
*Feb 26 02:05:53.691: htsp_process_event: [0/0/0, FXOLS_PROCEEDING, E_HTSP_PROCEEDING]fxols_offhook_proc
*Feb 26 02:05:53.691: htsp_timer - 120000 msec
*Feb 26 02:05:53.699: htsp_pre_connect_disconnect, cdb = 45D45960 cause = 26

*Feb 26 02:05:53.699: htsp_process_event: [0/0/0, FXOLS_PROCEEDING, E_HTSP_PRE_CONN_DISC]
*Feb 26 02:05:53.987: htsp_process_event: [0/0/0, FXOLS_OFFHOOK, E_DSP_SIG_0110]fxols_offhook_rvs_battery
*Feb 26 02:05:53.987: htsp_timer_stop2
*Feb 26 02:05:54.227: htsp_process_event: [0/0/0, FXOLS_CONNECT, E_DSP_SIG_0100]fxols_normal_battery fxols_disc_confirm
*Feb 26 02:05:54.227: htsp_timer_stop
*Feb 26 02:05:54.227: htsp_timer_stop2
*Feb 26 02:05:54.227: htsp_timer_stop3
*Feb 26 02:05:54.227: htsp_process_event: [0/0/0, FXOLS_POWER_DENIAL, E_HTSP_RELEASE_REQ]fxols_offhook_release
*Feb 26 02:05:54.227: htsp_timer_stop
*Feb 26 02:05:54.227: htsp_timer_stop2
*Feb 26 02:05:54.227: htsp_timer_stop3
*Feb 26 02:05:54.227: [0/0/0] set signal state = 0x4 timestamp = 0
*Feb 26 02:05:54.227: htsp_timer - 2000 msec
*Feb 26 02:05:55.783: htsp_process_event: [0/0/0, FXOLS_GUARD_OUT, E_DSP_SIG_0110]
*Feb 26 02:05:56.227: htsp_process_event: [0/0/0, FXOLS_GUARD_OUT, E_HTSP_EVENT_TIMER]fxols_guard_out_timeout
*Feb 26 02:05:56.227: htsp_process_event: [0/0/0, FXOLS_ONHOOK, E_DSP_SIG_0100]