1秒以上挂机正常挂断日志
BCM96328 xDSL Router
Login: e8ehome
Password:
> sh
BusyBox v1.00 (2011.05.04-10:01+0000) Built-in shell (msh)
Enter 'help' for a list of built-in commands.
# nmm
** NMM Voice Client **
NMM Client: Establishing communication with server 127.0.0.1:4000.......
Done
Welcome to MXP command shell. Use 'exit' to exit.
MXP> 0009735622 - TIU: set hook state to brcm endpoin db_status:1 offhook:1
0009735622 - [DSP_BCM] adapter_brcm_set_hook_state:lineId=0, cnxId=0! hook_state:1
0009735622 - TIUPDR: 0, 'idle' gets event 'offhook'
0009735762 - TIU: 0, hook status = 1
0009735762 - SPU: 0, Local hook=1
0009735762 - spuWrapTIU 0: Received set_ring_cad, ringing=0 ...
0009735762 - spuWrapTIU 0: .....set_ring_cad, ringing=0 ...
0009735762 - TIU: 0, set ring cadence=0 type = 255
0009735762 - spuWrapTIU 0: Recvd digit_detect enable=1
0009735762 - spuWrapTIU 0: ....digit_detect enable=1
0009735762 - TIUPDR: 0, 'idle' gets event 'enable'
0009735762 - TIUPDR: 0, state change 'idle' ==> 'ready'
0009735762 - NMM: Recieved voice event PhoneOffHook(5) from anon(4294967295), size 4
0009735762 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009735762 - SPU: 0, Hook State OFF_HOOK
0009735762 - SFU: dispatch_gen_msg: msg_id =9
0009735762 - SFU_handle_xmc_event EVENT spu_event=0, port=0, tcid=65535
0009735762 - SFU: sfu_add_call_leg on port=0
0009735762 - SFU did not insert IE to BLOCK CID on port 0, xmc_id = 65535, feature_state=0, per_call_state=0
0009735762 - SFU did NOT insert IE to FORCE G711 on port 0, xmc_id = 65535 (disabled)
0009735762 - SFU insert IE for Tel Url on port 0, xmc_id = 65535, tel_url_type = 0
0009735762 - sfu: sfu_alarm_clock_stop_timer2 enter
0009735762 - sfu: sfu_alarm_clock_stop_timer2 ignore!
0009735762 - spup_set_appearance - XMC_FOREGROUND_CALL on tcid 0
0009735762 - LFXS: 0, idle gets event tele_seize
0009735762 - LFXS: 0, idle ==> seize
0009735762 - LSS: 0, voice_enable=0
0009735762 - SPU: 0, previous tone=255 tone_gen tone=255, to_tele=1, to_net=0
0009735762 - CPS: 0, idle gets event lss_seize_ind
0009735762 - CPS: 0, idle ==> in_wait_dsp
0009735762 - SPU: 0, Net set busy=1
0009735762 - SPU: 0, Starting coding request coding=3
0009735762 - SPU: 0, spup_raw_coding_request coding=3 appearance=0
0009735762 - SPU: port=0, initiate new call recvd
0009735762 - tcid = 0, simulation_test.test_type = 0, state=0, selc=0, inprog=0
0009735858 - AMU: 0, Report State oper_state=2 admin_state=2 call_state=2
0009735858 - AMU: 0, Report DSP 0 channel 0 opened with coding = 3
0009735858 - SFU: dispatch_gen_msg: msg_id =3
0009735858 - SFU: tsg_state_to_sfu_state - converted tsg_state=2 to sfu_state=3
0009735858 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=3, sfu_id=93, apperance= 0
0009735858 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009735858 - SPU: 0,
Coding Request completed coding=3
0009735858 - CPS: 0, in_wait_dsp gets event coding_ready
0009735858 - SPU: 0, update_local_chan_prof num_prof=12, Pload_type=8, dflt_tx_idx= 1
0009735858 - SPU: 0, Disable voice path
0009735858 - CPS: 0, in_wait_dsp ==> collect
0009735858 - SPU: 0, Net set busy=1
0009735858 - SPU: 0, ATPM start
0009735858 - LFXS: 0, seize gets event ready
0009735858 - LFXS: 0, seize ==> dial_tone
0009735858 - SPU: 0, set coding new_state=COLLECT old_state=IDLE
0009735858 - SPU: 0, previous tone=255 tone_gen tone=18, to_tele=1, to_net=0
0009735858 - LSSLFXS: 0, state: lfxs_dial_tone(), dial_tone_id = 18
0009735858 - tcid = 0, simulation_test.test_type = 0, state=0, selc=0, inprog=0
0009735858 - SPU: 0, no need to set coding response sent call_ref=1b, tx_idx=0
0009735858 - SPU: 0, coding_ready 3
0009735898 - NMM: 0, get_tone_table_entry(Tone_id=18) Using Default tone table
0009735898 - AMU: 0, Report State oper_state=2 admin_state=2 call_state=2
0009735898 - SFU: dispatch_gen_msg: msg_id =3
0009735898 - SFU: tsg_state_to_sfu_state - converted tsg_state=2 to sfu_state=3
0009735898 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=3, sfu_id=93, apperance= 0
0009735898 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009738998 - CPS: 0, collect gets event tele_digit
0009738998 - port=0 spup_get_local_dial_string 0
0009739118 - CPS: 0, collect gets event tele_digit_off
0009739118 - SPU: 0, ATPM digit=1 type=0
0009739118 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009739118 - LFXS: 0, dial_tone gets event digit
0009739118 - receiving
0009739118 - spup_cst_report_status tcid=0, event=128, result=3
0009739118 - NMM: Recieved voice event unknown(128) from spu(4), size 8
0009739118 - SPU: 0, previous tone=18 tone_gen tone=255, to_tele=1, to_net=0
0009739118 - LFXS: 0, dial_tone ==> collect
0009739118 - lcse_entry
0009739118 - tcid = 0, simulation_test.test_type = 0, state=0, selc=0, inprog=0
0009739118 - no more digit
0009739118 - port=0 spup_get_local_dial_string 0
0009739118 - Dispatching sys event 262225 for voice event 50
0009739118 - Dispatching sys event 262243 for voice event 128
0009739358 - CPS: 0, collect gets event tele_digit
0009739358 - port=0 spup_get_local_dial_string 0
0009739468 - CPS: 0, collect gets event tele_digit_off
0009739468 - SPU: 0, ATPM digit=3 type=0
0009739468 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009739468 - LFXS: 0, collect gets event digit
0009739468 - port=0 spup_get_local_dial_string 0
0009739468 - Dispatching sys event 262225 for voice event 50
0009739698 - CPS: 0, collect gets event tele_digit
0009739698 - port=0 spup_get_local_dial_string 0
0009739768 - CPS: 0, collect gets event tele_digit_off
0009739768 - SPU: 0, ATPM digit=5 type=0
0009739768 - NMM: Rec
ieved voice event unknown(50) from anon(4294967295), size 136
0009739768 - LFXS: 0, collect gets event digit
0009739768 - port=0 spup_get_local_dial_string 0
0009739768 - Dispatching sys event 262225 for voice event 50
0009740398 - CPS: 0, collect gets event tele_digit
0009740398 - port=0 spup_get_local_dial_string 0
0009740548 - CPS: 0, collect gets event tele_digit_off
0009740548 - SPU: 0, ATPM digit=0 type=0
0009740548 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009740548 - LFXS: 0, collect gets event digit
0009740548 - port=0 spup_get_local_dial_string 0
0009740548 - Dispatching sys event 262225 for voice event 50
0009740798 - CPS: 0, collect gets event tele_digit
0009740798 - port=0 spup_get_local_dial_string 0
0009740958 - CPS: 0, collect gets event tele_digit_off
0009740958 - SPU: 0, ATPM digit=1 type=0
0009740958 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009740958 - LFXS: 0, collect gets event digit
0009740958 - port=0 spup_get_local_dial_string 0
0009740958 - Dispatching sys event 262225 for voice event 50
0009741268 - CPS: 0, collect gets event tele_digit
0009741268 - port=0 spup_get_local_dial_string 0
0009741348 - CPS: 0, collect gets event tele_digit_off
0009741348 - SPU: 0, ATPM digit=8 type=0
0009741348 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009741348 - LFXS: 0, collect gets event digit
0009741348 - port=0 spup_get_local_dial_string 0
0009741348 - Dispatching sys event 262225 for voice event 50
0009741768 - CPS: 0, collect gets event tele_digit
0009741768 - port=0 spup_get_local_dial_string 0
0009741878 - CPS: 0, collect gets event tele_digit_off
0009741878 - SPU: 0, ATPM digit=4 type=0
0009741878 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009741878 - LFXS: 0, collect gets event digit
0009741878 - port=0 spup_get_local_dial_string 0
0009741878 - Dispatching sys event 262225 for voice event 50
0009742608 - CPS: 0, collect gets event tele_digit
0009742608 - port=0 spup_get_local_dial_string 0
0009742678 - CPS: 0, collect gets event tele_digit_off
0009742678 - SPU: 0, ATPM digit=3 type=0
0009742678 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009742678 - LFXS: 0, collect gets event digit
0009742678 - port=0 spup_get_local_dial_string 0
0009742678 - Dispatching sys event 262225 for voice event 50
0009742798 - CPS: 0, collect gets event tele_digit
0009742798 - port=0 spup_get_local_dial_string 0
0009742878 - CPS: 0, collect gets event tele_digit_off
0009742878 - SPU: 0, ATPM digit=3 type=0
0009742878 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009742878 - LFXS: 0, collect gets event digit
0009742878 - port=0 spup_get_local_dial_string 0
0009742878 - Dispatching sys event 262225 for voice event 50
000974312
8 - CPS: 0, collect gets event tele_digit
0009743128 - port=0 spup_get_local_dial_string 0
0009743248 - CPS: 0, collect gets event tele_digit_off
0009743248 - SPU: 0, ATPM digit=1 type=0
0009743248 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009743248 - LFXS: 0, collect gets event digit
0009743248 - port=0 spup_get_local_dial_string 0
0009743248 - Dispatching sys event 262225 for voice event 50
0009743818 - CPS: 0, collect gets event tele_digit
0009743818 - port=0 spup_get_local_dial_string 0
0009743888 - CPS: 0, collect gets event tele_digit_off
0009743888 - SPU: 0, ATPM digit=0 type=0
0009743888 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009743888 - LFXS: 0, collect gets event digit
0009743888 - port=0 spup_get_local_dial_string 0
0009743888 - NMM: Recieved voice event unknown(92) from anon(4294967295), size 132
0009743888 - SPU: 0, ATPM result=0
0009743888 - SPU: 0, ATPM result=0
0009743888 - CPS: 0, collect gets event tele_addr_collect_good
0009743888 - SPU: 0, ATPM end
0009743888 - ReceivedEnd
0009743888 - spup_cst_report_status tcid=0, event=128, result=4
0009743888 - NMM: Recieved voice event unknown(128) from spu(4), size 8
0009743888 - CPS: 0, collect ==> request
0009743888 - SPU: 0, previous tone=255 tone_gen tone=255, to_tele=1, to_net=0
0009743888 - SPU: 0, Send net setup hunt_group_id=2, fwd_digits=135********
0009743888 - SPU: 0: Remote end Caller ID info:
0009743888 - SPU: 0: Date = 0 1/ 0 1
0009743888 - SPU: 0: Time = 0 0: 0 0
0009743888 - SPU: 0: Calling Number = 135********
0009743888 - SPU: 0: Calling Party Name = repeat dial callback
0009743888 - NMM: Recieved voice event unknown(65) from anon(4294967295), size 132
0009743888 - LFXS: 0, collect gets event addr_done
0009743888 - LFXS: 0, collect ==> call_progress
0009743888 - LSS: 0, voice_enable=1
0009743888 - SPU: 0, set coding new_state=IDLE old_state=COLLECT
0009743888 - port=0 spup_get_local_dial_string 0
0009743888 - AMU: 0, Report State oper_state=2 admin_state=2 call_state=3
0009743888 - SFU: dispatch_gen_msg: msg_id =3
0009743888 - SFU: tsg_state_to_sfu_state - converted tsg_state=3 to sfu_state=5
0009743888 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=5, sfu_id=93, apperance= 0
0009743888 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009743888 - nw_bind_net_dev socket=23 <----> dev= success!!!
0009743888 - NWIF: nw_open() - Socket 23 is allocated and bound. Associating it with a CCB.
0009743888 - nwif_fdset(): fd 23, max fd 24
0009743888 - nw_get_rtp_data(): num_message 1
0009743888 - NWIF: nw_open() - connection is set up, port = 16054, socket 23 , Returned id (CCB#) = 2.
0009743888 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009743888 - NWIF: nw_open() - port_type 3
0009743888 - NWIF: nw_open() 5.0.217.45:16055 ->
0009743888 - NWIF: nw_open() - Binding a socket to address 500d92d.
0009743888 - NWIF: nw_setsockopt() socket 24 TOS=0000, COS=0000
0009743888 - NWIF: nw_open() - for local call, release dev binding
0009743888 - nw_bind_net_dev socket=24 <----> dev= success!!!
0009743888 - NWIF: nw_open() - Socket 24 is allocated and bound. Associating it with a CCB.
0009743888 - nwif_fdset(): fd 24, max fd 25
0009743888 - nw_get_rtp_data(): num_message 1
0009743888 - NWIF: nw_open() - connection is set up, port = 16055, socket 24 , Returned id (CCB#) = 1.
0009743888 - SSMU: 0, call_id=0, state IDLE gets event P2C_SETUP
0009743888 - PSU: psup_smc2_setup_resp - call ref = 0000001c
0009743888 - PSU: psup_state_machine_execute - call ref= 0000001c, state= PSU_STATE_WAIT_SETUP_RESP, event= PSU_EVT_SMC2_SETUP_RESP
0009743888 - PSU: PSU state change - PSU_STATE_WAIT_SETUP_RESP -> PSU_STATE_WAIT_NET_CONN
0009743888 - PSU: psup_smc2_suppl_info - call ref = 0000001c, h_ie = 0x2aedb194
0009743888 - SPU: 0, Recvd suppl_info hie=0x2aedb194
0009743888 - SFU: dispatch_gen_msg: msg_id =4
0009743888 - SFU: sfu_find_call_leg XMC_id=0 on port=0, tele_port=0, sfu_id= 93
0009743888 - SFU: GG_IE_SUPPL_SMU_CALLREF_IE received, port=0, xmc_id=0
0009743888 - SSMU_SEND_INVITE Entered, call=0x2ad73298
0009743888 - AppRouteHopListInsertToMsg enter, tcid=0
0009743888 - SSMU: RV call-leg 0xceb680 was created for SSMU call 0x2ad73298
0009743888 - SSMU: Contact is "sip:+862150442030@5.0.217.45:5058"
0009743888 - SSMU: send_nw_invite Transport is 0 for tcid 0
0009743888 - SSMU:0 Setting TiemrB 10000
0009743888 - SSMU:0 CallLeg Outbound: 0:5.0.217.10:5060
0009743888 - ERROR - CALL - RvSipCallLegSessionTimerSetPreferenceParams - call-leg 0x0xceb680: stack doesn't support session timer
0009743888 - Unable to set Session Timer Parameters 0xceb680, status=-1
0009743888 - SSMU: Connecting a call: From: "Anonymous"
0009743888 - SSMU CallLegMsgToSendEvHandler Entered, hAppCallLeg=0x2ad73298, call-leg 0xceb680
Payload Type=4
Coding Type=G723-63
Vif=384
Profile Index: 8 - Type=VOICE(0)
Payload Type=4
Coding Type=G723-53
Vif=320
Profile Index: 9 - Type=T38-UDP(6)
Payload Type=129
Coding_type=T38-UDP
Vif=288
Version=0
MaxBitRate=144
Profile Index: 10 - Type=VOICE(0)
Payload Type=116
Coding Type=iLBC
Vif=304
Profile Index: 11 - Type=VOICE(0)
Payload Type=9
Coding Type=G722
Vif=1280
0009744618 - SSMU assemble_sdp_media....pload_type = 0
0009744618 - SSMU: ssmu_util_get_insert_index - 6
0009744618 - SSMU: ssmu_util_get_insert_index - 0 fmt_map - num_fmt=1, fmt=8, index=1
0009744618 - SSMU assemble_sdp_media....pload_type = 8
0009744618 - SSMU assemble_sdp_media....pload_type = 102
0009744618 - SSMU: ssmu_util_get_insert_index - 4
0009744618 - SSMU: ssmu_util
_get_insert_index - 0 fmt_map - num_fmt=2, fmt=8, index=1
0009744618 - SSMU: ssmu_util_get_insert_index - 1 fmt_map - num_fmt=2, fmt=0, index=0
0009744618 - SSMU: ssmu_util_get_insert_index - CODEC type=0, prio=6
0009744618 - SSMU assemble_sdp_media....pload_type = 18
0009744618 - SSMU: ssmu_util_get_insert_index - 2
0009744618 - SSMU: SIP MESSAGE ----> TX: to Addr=5.0.217.10:5060, UDP
INVITE sip:135********@https://www.360docs.net/doc/763353958.html, SIP/2.0
From: "Anonymous"
To:
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
CSeq: 26334 INVITE
Via: SIP/2.0/UDP 5.0.217.45:5058;branch=z9hG4bK-5d1-16b892-49948b35
Route:
Route:
Max-Forwards: 70
Supported: replaces,100rel
Contact:
User-Agent: TITAN
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,INFO,NOTIFY,PRACK,MESSAGE,UPDATE
P-Preferred-Identity:
Content-Type: application/sdp
Content-Length: 363
v=0
o=a0000 8671 6672 IN IP4 5.0.217.45
s=SIP Call
c=IN IP4 5.0.217.45
t=0 0
m=audio 16054 RTP/AVP 8 18 4 102 9 0
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:4 G723-63/8000
a=fmtp:4 bitrate=6.3;annexa=no
a=rtpmap:102 G726-24/8000
a=rtpmap:9 G722/16000
a=rtpmap:0 PCMU/8000
a=ptime:10
a=sendrecv
a=rtcp-xr:voip-metrics
0009744618 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to Inviting, reason:0, method=-1
0009744618 - SSMU CallLegStateChanged to INVITING
0009744618 - SSMU: 0 IDLE ==> WAIT_INV_PROV_RESP
0009744645 - SSMU: SIP MESSAGE <---- RX: SIP/2.0 100 Tryi
0009744645 - SSMU: SIP MESSAGE <---- RX: to Addr=5.0.217.10:5060, UDP
SIP/2.0 100 Trying
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
Via: SIP/2.0/UDP 5.0.217.45:5058;received=5.0.217.45;branch=z9hG4bK-5d1-16b892-49948b35
To:
From: "Anonymous"
CSeq: 26334 INVITE
Date: Thu, 23 Jun 2011 07:19:27 GMT
Server: Alcatel-Lucent-HPSS/3.0.3
Content-Length: 0
0009744645 - SSMU CallLegMsgReceivedEvHandler Entered, hAppCallLeg=0x2ad73298, hCallLeg=0xceb680
0009744645 - SSMU rxed Msg msgType=-1, rspStatusCode=100, state=Inviting
0009744645 - SSMU: call-leg 0xceb680: p_call 0x2ad73298- RV ReceivedMsg: OtherParam =
0009744645 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009744645 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009744645 - SSMU extract_sdp....Entered. sdp_state = 2
0009744645 - SSMU extract_sdp...., is_sdp_present = 0, session_version=0
0009744645 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to PROCEEDING, reason:26, method=0
0009752
242 - SSMU: SIP MESSAGE <---- RX: SIP/2.0 183 Sess
0009752242 - SSMU: SIP MESSAGE <---- RX: to Addr=5.0.217.10:5060, UDP
SIP/2.0 183 Session Progress
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
CSeq: 26334 INVITE
From: "Anonymous"
To:
Via: SIP/2.0/UDP 5.0.217.45:5058;received=5.0.217.45;branch=z9hG4bK-5d1-16b892-49948b35
Server: DC-SIP/2.0
Allow: INVITE,BYE,REGISTER,ACK,OPTIONS,CANCEL,SUBSCRIBE,NOTIFY,PRACK,INFO,REFER,UPDATE
Require: 100rel
RSeq: 1
Contact:
Content-Type: application/sdp
Content-Length: 242
v=0
o=LucentPCSF 1587222724 1587222724 IN IP4 https://www.360docs.net/doc/763353958.html,
s=-
c=IN IP4 5.0.217.133
t=0 0
m=audio 28444 RTP/AVP 8 127
a=rtpmap:8 PCMA/8000
a=rtpmap:127 telephone-event/8000
a=fmtp:127 0-15
a=ptime:10
a=maxptime:30
0009752242 - SSMU CallLegMsgReceivedEvHandler Entered, hAppCallLeg=0x2ad73298, hCallLeg=0xceb680
0009752242 - SSMU rxed Msg msgType=-1, rspStatusCode=183, state=PROCEEDING
0009752242 - SSMU: call-leg 0xceb680: p_call 0x2ad73298- RV ReceivedMsg: OtherParam =
0009752242 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009752242 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009752242 - SSMU extract_sdp....Entered. sdp_state = 2
0009752242 - SSMU:extract_sdp: buffer ================
0009752242 - SSMU: rvSdpOriginGetVersion =1587222724,1587222724
0009752242 - SSMU:extract_sdp: media lines in sdp=1
0009752242 - SSMU: ssmu_rv_extract_sdp_media Entered, media_type=0
0009752242 - SSMU: ssmu_rv_extract_sdp_media: fmt_cnt = 2
0009752242 - SSMU: ssmu_find_other_header, GetName P-Early-Media Failed, Hmsg=0xc7b278
0009752242 - SSMU: ssmu_rv_extract_sdp_media: num_attr (ALL) = 5
0009752242 - SSMU: switch to voice status!!!!!!
0009752242 - SSMU: ssmu_rv_extract_sdp_media: map_cnt = 2 num_vbd = 0
0009752242 - SSMU: extract_sdp_media DTMF PAYLOAD 127
0009752242 - ssmup_process_PLR(): num_red = 0
0009752242 - SSMU extract_sdp_from_rv....sdp body present and extracted
0009752242 - SSMU extract_sdp...., is_sdp_present = 1, session_version=1587222724
0009752242 - SSMU: 0, ssmu_transition_sdp_state sdp_event=1, current_state = 2 Old State = 0
0009752242 - SSMU: 0, ssmu_transition_sdp_state old_state = 2, sdp_event=1, new_state = 3
0009752242 - SSMU: 0, call_id=0, state WAIT_INV_PROV_RESP gets event NW_PROG_RSP
0009752242 - PSU: psup_smc2_net_connect - call ref = 0000001c, more digit? no, cp_tone = 255
0009752242 - PSU: psup_state_machine_execute - call ref= 0000001c, state= PSU_STATE_WAIT_NET_CONN, event= PSU_EVT_SMC2_NET_CONNECT
0009752242 - CMGR: Call ref 0000001C. Call state change. Party ref 00000000
call_state
2, p_call_profile (nil)
0009752242 - PSU: PSU state change - PSU_STATE_WAIT_NET_CONN -> PSU_STATE_WAIT_TELE_CONN
0009752242 - SPU: 0, Recv net connect more_digits=0 cp_tone=255
0009752242 - PSU: psup_smc1_voice_connect - call ref = 0000001c, client call ref = 00000000
0009752242 - CPS: 0, request gets event net_connect_no_overlap
0009752242 - CPS: 0, request ==> in_wait_answer
0009752242 - Applied HSPKTRATE 0 valid_params 0
0009752242 - SPU: 0, spup_turn_onoff_ring ring_delay=0, hook=1, delay_ring_timer=0, on=1, port_num=1
0009752242 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009752242 - NWIF: nw_media_rtp_param_change() - 2 eth4_1 5.0.217.45 --> 5.0.217.133
0009752242 - NWIF: nw_media_rtp_param_change() - for non-local call, do dev binding, eth4_1
0009752242 - nw_bind_net_dev socket=23 <----> dev=eth4_1 success!!!
0009752242 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009752242 - NWIF: nw_media_rtcp_param_change() - 1 eth4_1 5.0.217.45 --> 5.0.217.133
0009752242 - NWIF: nw_media_rtcp_param_change() - for non-local call, do dev binding, eth4_1
0009752242 - nw_bind_net_dev socket=24 <----> dev=eth4_1 success!!!
0009752242 - PSU: psup_smc2_set_coding - call ref = 0000001c, coding = 0x2ad74428
0009752242 - SPU: 0, Recv set coding coding num_prof=2, Pload_type=8
0009752242 - CPS: 0, in_wait_answer gets event set_coding_from_net
0009752242 - SPU: 0 local media ip addr is 0x0500d92d
0009752242 - NWIF: retrieveMacEx: netmask 255.0.217.133 ffffff00, gw 0.217.133 0500d901, ip 5.0.217.133 0500d985, port 6f1c.
0009752242 - NWIF: target in LAN retrieveMacEx: tIp 5.0.217.133 0500d985.
0009752242 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009752242 - getArpEntry Entry not available in cache,arp for IP 5.0.217.133, dev eth4_1
0009752242 - NWIF: sendProbe2Target addr 0500d985.
0009752242 - NWIF: success!
0009752242 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009752242 - NWIF: getArpEntry HWAddr found : 00:00:00:00:00:00
0009752242 - NWIF: getArpEntry entry retreived invalid mac.
0009752242 - SPU: 0 update_remote_chan_prof get remote mac failed 0 times. return 2
0009752242 - SSMU: 0, ssmup_proc_connect - c2p set_coding first PT 8
0009752242 - SSMU: 0 WAIT_INV_PROV_RESP ==> WAIT_INV_ACCEPT_RESP
0009752242 - SSMU CallLegMsgToSendEvHandler Entered, hAppCallLeg=0x2ad73298, call-leg 0xceb680
0009752242 - SSMU toSend Msg msgType=7, rspStatusCode=-1, state=PROCEEDING
0009752242 - SSMU: init supported = replaces,100rel
0009752242 - SSMU: ssmu_insert_other_header 'Supported'
0009752242 - SSMU: ssmu_insert_other_header 'User-Agent'
0009752242 - SSMU: tcid 0, ssmu_insert_compact_form_header enter
0009752242 - AppMsgToSendUpdateContact enter, tcid=0
0009752242 - AppMsgToSendSetPPreferredIdentity enter, tcid=0
0009752242 - SSMU:0 ssmu_via_header_stun_proc enter
0009752242 - SSMU
: SIP MESSAGE ----> TX: PRACK sip:pcsf-s
0009752242 - SSMU rxed Msg msgType=-1, rspStatusCode=200, state=PROCEEDING
0009752242 - SSMU: call-leg 0xceb680: p_call 0x2ad73298- RV ReceivedMsg: OtherParam =
0009752242 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009752242 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009752242 - SSMU pfnPrackStateChangedEvEvHandler eState=2
0009752282 - NWIF: retrieveMacEx: netmask 255.0.217.133 ffffff00, gw 0.217.133 0500d901, ip 5.0.217.133 0500d985, port 6f1c.
0009752282 - NWIF: target in LAN retrieveMacEx: tIp 5.0.217.133 0500d985.
0009752282 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009752282 - NWIF: getArpEntry HWAddr found : 00:00:00:00:00:00
0009752282 - NWIF: getArpEntry entry retreived invalid mac.
0009752282 - NWIF: sendProbe2Target addr 0500d985.
0009752282 - NWIF: success!
0009752282 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009752282 - NWIF: getArpEntry HWAddr found : 00:00:00:00:00:00
0009752282 - NWIF: getArpEntry entry retreived invalid mac.
0009752282 - SPU: 0 update_remote_chan_prof get remote mac failed 1 times. return 2
0009752305 - NWIF: retrieveMacEx: netmask 255.0.217.133 ffffff00, gw 0.217.133 0500d901, ip 5.0.217.133 0500d985, port 6f1c.
0009752305 - NWIF: target in LAN retrieveMacEx: tIp 5.0.217.133 0500d985.
0009752305 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009752305 - NWIF: getArpEntry HWAddr found : 00:00:0c:9f:f0:0a
0009752305 - NWIF: getArpEntry entry has been successfully retreived.
0009752305 - SPU: 0 update_remote_chan_prof remote mac 00:00:0c:9f:f0:0a
0009752305 - ##################Set control##################
0009752305 - SPU: 0, update_remote_chan_prof num_prof=2 tx_idx=0, rx_idx = 0 payload_type=8 prof_type=0
0009752305 - SPU: 0, update_remote_chan_prof coding=3 type=0 coding_type=8 appearance=0
0009752305 - SPU: 0, wideband_chan 0
0009752305 - SSMU: ssmu_nmm_check_media_route med_addr already has the same ip section, remote_addr=5.0.0.0
0009752305 - SSMU: ssmu_nmm_add_media_route check route failure, addr=5.0.217.133
0009752305 - SPU: 0 call_mode 5(voice=5 fax=6 modem=7) fax_modem_state=0
0009752305 - SPU: 0, cur_codec is pcma(0)
0009752305 - NMM: Recieved voice event unknown(52) from anon(4294967295), size 136
0009752305 - SPU: 0 update_remote_chan_prof open ec
0009752358 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009752358 - NWIF: nw_media_rtcp_param_change() - 1 eth4_1 5.0.217.45 --> 5.0.217.133
0009752358 - NWIF: nw_media_rtcp_param_change() - for non-local call, do dev binding, eth4_1
0009752358 - nw_bind_net_dev socket=24 <----> dev=eth4_1 success!!!
0009752358 - SSMU 0: call_id = 0, ssmup_insert_connection_mode_ie: Normal conn_mode=4 when ip=5.0.217.133
0009752358 - PSU: psup_smc2_voice_connect
- call ref = 0000001c, client call ref = 2ad73298
0009752358 - SPU: 0, Recv voice connect psu_data_call_ref=0000001C
0009752358 - SPU: 0, Recvd Connection Mode IE: 4
0009752358 - SPU: 0, Enable voice path mode=4
0009752358 - SSMU: 0, wait_inv_accept_resp Received 183_prog_resp sending voice_connect
0009755418 - ssmup_tx_rtcp_data
0009755595 - SSMU: SIP MESSAGE <---- RX: SIP/2.0 200 OK
0009755595 - SSMU: SIP MESSAGE <---- RX: to Addr=5.0.217.10:5060, UDP
SIP/2.0 200 OK
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
CSeq: 26334 INVITE
From: "Anonymous"
To:
Via: SIP/2.0/UDP 5.0.217.45:5058;received=5.0.217.45;branch=z9hG4bK-5d1-16b892-49948b35
Server: DC-SIP/2.0
Supported: timer
Session-Expires: 1800;refresher=uas
Allow: INVITE,BYE,REGISTER,ACK,OPTIONS,CANCEL,SUBSCRIBE,NOTIFY,INFO,REFER,UPDATE
Content-Length: 0
Contact:
0009755595 - SSMU CallLegMsgReceivedEvHandler Entered, hAppCallLeg=0x2ad73298, hCallLeg=0xceb680
0009755595 - SSMU rxed Msg msgType=-1, rspStatusCode=200, state=PROCEEDING
0009755595 - SSMU: call-leg 0xceb680: p_call 0x2ad73298- RV ReceivedMsg: OtherParam =
0009755595 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009755595 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009755595 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to REMOTE_ACCEPTED, reason:7, method=0
0009755595 - SSMU: 0, call_id=0, state WAIT_INV_ACCEPT_RESP gets event NW_ACCEPTED_RSP
0009755595 - SSMU:0 ssmup_proc_connect attempted
0009755595 - process sdp negotiation;select remote sdp profile to negotiation
0009755595 - Applied HSPKTRATE 0 valid_params 0
0009755595 - SPU: 0, spup_turn_onoff_ring ring_delay=0, hook=1, delay_ring_timer=0, on=1, port_num=1
0009755595 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009755595 - NWIF: nw_media_rtp_param_change() - 2 eth4_1 5.0.217.45 --> 5.0.217.133
0009755595 - NWIF: nw_media_rtp_param_change() - for non-local call, do dev binding, eth4_1
0009755595 - nw_bind_net_dev socket=23 <----> dev=eth4_1 success!!!
0009755595 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009755595 - NWIF: nw_media_rtcp_param_change() - 1 eth4_1 5.0.217.45 --> 5.0.217.133
0009755595 - NWIF: nw_media_rtcp_param_change() - for non-local call, do dev binding, eth4_1
0009755595 - nw_bind_net_dev socket=24 <----> dev=eth4_1 success!!!
0009755595 - PSU: psup_smc2_set_coding - call ref = 0000001c, coding = 0x2ad74428
0009755595 - SPU: 0, Recv set coding coding num_prof=2, Pload_type=8
0009755595 - CPS: 0, in_wait_answer gets event set_coding_from_net
0009755595 - SPU: 0 local media ip addr is 0x0500d92d
0009755595 - NWIF: retrieveMacEx: netmask 255.0.217.133 ffffff00, gw 0.217.133 0500d901, ip 5.0.217.133 0500d
985, port 6f1c.
0009755595 - NWIF: target in LAN retrieveMacEx: tIp 5.0.217.133 0500d985.
0009755595 - NWIF: getArpEntry Find arp entry for IP 5.0.217.133, dev eth4_1, socket fd: 25.
0009755595 - NWIF: getArpEntry HWAddr found : 00:00:0c:9f:f0:0a
0009755595 - NWIF: getArpEntry entry has been successfully retreived.
0009755595 - SPU: 0 update_remote_chan_prof remote mac 00:00:0c:9f:f0:0a
0009755595 - ##################Set control##################
0009755595 - SPU: 0, restoring coding state=2
0009755595 - SPU: 0, set coding response sent call_ref=1c, tx_idx=0
0009755595 - PSU: psup_smc1_set_coding_resp - call ref = 0000001c, coding = 0x2ad2cac8
0009755595 - SPU: 0, coding_ready 3
0009755595 - CPS: 0, in_wait_answer gets event coding_ready
0009755595 - SPU: 0, Set DTMF relay control comand isssued (as per profile)
0009755595 - spup_dtmf_relay_per_profile GG_RED_2833 --- disabled
0009755595 - SSMU_SEND_ACK Entered for call=0x2ad73298
0009755595 - SSMU CallLegMsgToSendEvHandler Entered, hAppCallLeg=0x2ad73298, call-leg 0xceb680
0009755595 - SSMU toSend Msg msgType=1, rspStatusCode=-1, state=REMOTE_ACCEPTED
0009755595 - SSMU: ssmu_insert_other_header 'User-Agent'
0009755595 - SSMU: tcid 0, ssmu_insert_compact_form_header enter
0009755595 - AppMsgToSendUpdateContact enter, tcid=0
0009755595 - AppMsgToSendSetPPreferredIdentity enter, tcid=0
0009755595 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009755595 - SSMU:0 ssmu_via_header_stun_proc enter
0009755595 - SSMU: SIP MESSAGE ----> TX: ACK sip:5.0.217.
0009755595 - SSMU: SIP MESSAGE ----> TX: to Addr=5.0.217.10:5060, UDP
ACK sip:5.0.217.10:5060 SIP/2.0
From: "Anonymous"
To:
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
CSeq: 26334 ACK
Via: SIP/2.0/UDP 5.0.217.45:5058;branch=z9hG4bK-5dc-16e648-3c8deb8b
Max-Forwards: 70
User-Agent: TITAN
Contact:
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,INFO,NOTIFY,PRACK,MESSAGE,UPDATE
Content-Length: 0
0009755595 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to Connected, reason:24, method=0
0009755595 - SSMU: 0, ssmu_transition_sdp_state sdp_event=2, current_state = 3 Old State = 2
0009755595 - SSMU: 0, ssmu_transition_sdp_state old_state = 3, sdp_event=2, new_state = 5
0009755595 - PSU: psup_smc2_tele_connect - call ref = 0000001c
0009755595 - SSMU: 0, ssmup_proc_connect - c2p set_coding first PT 8
0009755595 - SSMU: 0 WAIT_INV_ACCEPT_RESP ==> PRE_CONNECTED
0009755595 - Dispatching sys event 262227 for voice event 52
0009755595 - Dispatching sys event 262225 for voice event 50
0009755678 - PSU: psup_state_machine_execute - call ref= 0000001c, state= PSU_STATE_WAIT_TELE_CON
N, event= PSU_EVT_SMC2_TELE_CONNECT
0009755678 - CMGR: Call ref 0000001C. Call state change. Party ref 00000000
call_state 3, p_call_profile (nil)
0009755678 - PSU: PSU state change - PSU_STATE_WAIT_TELE_CONN -> PSU_STATE_CONNECTED
0009755678 - SPU: 0, Recv tele connect
0009755678 - CPS: 0, in_wait_answer gets event net_tele_connect
0009755678 - CPS: 0, in_wait_answer ==> connected
0009755678 - SPU: 0, previous tone=255 tone_gen tone=255, to_tele=1, to_net=0
0009755678 - CPS: 0, voice_enable=1
0009755678 - NMM: Recieved voice event unknown(93) from anon(4294967295), size 4
0009755678 - NMM: Recieved voice event unknown(91) from anon(4294967295), size 132
0009755678 - SFU: tsg_state_to_sfu_state - converted tsg_state=8 to sfu_state=8
0009755678 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=8, sfu_id=93, apperance= 0
0009755678 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009755678 - SSMU: 0 ssmup_set_coding_resp_msg received
0009755678 - SSMU: 0, call_id=0, state PRE_CONNECTED gets event PSU_SET_CODING_RESP
0009755678 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009755678 - NWIF: nw_media_rtp_param_change() - 2 eth4_1 5.0.217.45 --> 5.0.217.133
0009755678 - NWIF: nw_media_rtp_param_change() - for non-local call, do dev binding, eth4_1
0009755678 - nw_bind_net_dev socket=23 <----> dev=eth4_1 success!!!
0009755678 - NMM: nmmp_ssmu_get_med_dev: eth4_1
0009755678 - NWIF: nw_media_rtcp_param_change() - 1 eth4_1 5.0.217.45 --> 5.0.217.133
0009755678 - NWIF: nw_media_rtcp_param_change() - for non-local call, do dev binding, eth4_1
0009755678 - nw_bind_net_dev socket=24 <----> dev=eth4_1 success!!!
0009755678 - SSMU 0: call_id = 0, ssmup_insert_connection_mode_ie: Normal conn_mode=4 when ip=5.0.217.133
0009755678 - PSU: psup_smc2_voice_connect - call ref = 0000001c, client call ref = 2ad73298
0009755678 - SPU: 0, Recv voice connect psu_data_call_ref=0000001C
0009755678 - SPU: 0, Recvd Connection Mode IE: 4
0009755678 - SPU: 0, Enable voice path mode=4
0009755678 - SSMU: 0 ssmup_state_pre_connected - received set coding resp
0009755678 - SSMU: 0 PRE_CONNECTED ==> CONNECTED
0009755678 - SSMU: 0, Changing the QOS parameters vifbyte 80, viftime 10, pkt_sz 92, pkt_rate 10
0009755678 - Network: nw_qos_change(), size=92 bytes, rate=10 pkts/msc, k=0
0009755678 - SSMU: 0, CONNECTED QOS param not set *******************
0009755678 - ssmup_state_goto CONNECTED on tcid 0, call block 0
0009755678 - Dispatching sys event 262203 for voice event 93
0009755678 - Dispatching sys event 262201 for voice event 91
0009755678 - Dispatching sys event 262243 for voice event 128
0009755678 - Dispatching sys event 262221 for voice event 66
0009758736 - SSMU:0 RegClient Refreshing
0009758736 - SSMU: init supported = replaces,100rel
0009758736 - SSMU: ssmu_insert_other_header 'Supported'
0009758736 - SS
MU: ssmu_insert_other_header 'User-Agent'
0009758736 - SSMU:ssmu_process_auth_header()
0009758736 - SSMU: tcid 0, ssmu_insert_compact_form_header enter
0009758736 - Failed to get security-agreement state
0009758736 - SSMU:0 ssmu_via_header_stun_proc enter
0009758736 - SSMU: SIP MESSAGE ----> TX: REGISTER sip:sh.
0009758736 - SSMU: SIP MESSAGE ----> TX: to Addr=5.0.217.10:5060, UDP
REGISTER sip:https://www.360docs.net/doc/763353958.html, SIP/2.0
From:
To:
Call-ID: cedd68-500d92d-13c2-50029-3-30450198-3
CSeq: 12 REGISTER
Via: SIP/2.0/UDP 5.0.217.45:5058;branch=z9hG4bK-5df-16f27b-ebe66d9
Max-Forwards: 70
Supported: replaces,100rel
Expires: 3600
Contact:
User-Agent: TITAN
Content-Length: 0
0009758736 - SSMU:0 RegClient State changed to Registering (reason=0)
0009758736 - NMM: Recieved voice event unknown(51) from anon(4294967295), size 136
0009758736 - Dispatching sys event 262226 for voice event 51
0009758748 - SSMU: SIP MESSAGE <---- RX: SIP/2.0 200 OK
0009758748 - SSMU: SIP MESSAGE <---- RX: to Addr=5.0.217.10:5060, UDP
SIP/2.0 200 OK
Call-ID: cedd68-500d92d-13c2-50029-3-30450198-3
Via: SIP/2.0/UDP 5.0.217.45:5058;received=5.0.217.45;branch=z9hG4bK-5df-16f27b-ebe66d9
To:
From:
CSeq: 12 REGISTER
Allow-Events: reg
Contact:
Date: Thu, 23 Jun 2011 07:19:41 GMT
P-Associated-URI:
P-Associated-URI:
Server: Alcatel-Lucent-HPSS/3.0.3
Service-Route:
Service-Route:
Content-Length: 0
0009758748 - SSMU:0 RegClient State changed to Registered (reason=1)
0009758748 - SSMU:0 before expires=300
0009758748 - SSMU:0 after expires=150000
0009758748 - SSMU:0 Registration Refresh complete - Timer configured for re-registration
0009758748 - SSMU:0 Reg Refreshed
0009758748 - SSMU: Registration issued callback 3
0009760418 - ssmup_tx_rtcp_data
0009765418 - ssmup_tx_rtcp_data
0009769962 - TIU: set hook state to brcm endpoin db_status:0 offhook:0
0009769962 - [DSP_BCM] adapter_brcm_set_hook_state:lineId=0, cnxId=0! hook_state:0
0009769962 - TIUPDR: 0, 'ready' gets event 'onhook'
0009769962 - TIUPDR: 0, state change 'ready' ==> 'break'
0009770032 - TIUPDR: 0, 'break' gets event 'timeout'
0009770032 - TIUPDR: 0, state change 'break' ==> 'flash'
0009770418 - ssmup_tx_rtcp_data
0009770452 - TIU: 0, hook status = 0
0009770452 - SPU: 0, Local hook=0
0009770452 - spuWrapTIU 0: Recvd digit_detect enable=0
0009770452 - spuWrapTIU 0: ....digit_detect enable=0
0009770452 -
TIUPDR: 0, 'flash' gets event 'disable'
0009770452 - TIUPDR: 0, state change 'flash' ==> 'idle'
0009770452 - NMM: Recieved voice event PhoneOnHook(6) from anon(4294967295), size 4
0009770452 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009770452 - LFXS: 0, connected gets event onhook
0009770452 - spuWrapTIU 0: Received set_reverse_battery, reverse=0 ...
0009770452 - spuWrapTIU 0: .....set_reverse_battery=0 ...
0009770452 - TIU: 0, set reverse battery=0
0009770452 - SPU: 0, sending_on_hook to SFU XMC_ID = 0, SFU_ID = 93, PORT = 0
0009770452 - SPU: 0, Hook State ON_HOOK
0009770452 - SFU: dispatch_gen_msg: msg_id =9
0009770452 - SFU_handle_xmc_event EVENT spu_event=2, port=0, tcid=0
0009770452 - SFU: sfup_process_disconnect, port=0, tcid=0, feature=0
0009770452 - sfup_process_disconnect port=0, tcid=0, feature=NONE
0009770452 - sfu_default_disconnect_call_leg port=0, tcid=0, sfu_id=93
0009770452 - SPU: tcid=0, spu_release_call
0009770452 - SPU: tcid=0, release_call recvd
0009770452 - LFXS: 0, connected gets event tele_release
0009770452 - LFXS: 0, connected ==> disconnect_wait
0009770452 - CPS: 0, connected gets event lss_disconnect_ind
0009770452 - tcid = 0, simulation_test.test_type = 0, state=0, selc=0, inprog=0
0009770452 - tcid = 0, simulation_test.test_type = 0, selc=0, inprog=0
0009770452 - SPU: 0, Starting coding request coding=255
0009770452 - SPU: 0, spup_raw_coding_request coding=255 appearance=0
0009770452 - CPS: 0, connected ==> connected_hold
0009770452 - Dispatching sys event 262149 for voice event 6
0009770452 - Dispatching sys event 262225 for voice event 50
0009770508 - NMM: Recieved voice event unknown(86) from anon(4294967295), size 40
0009770508 - Dispatching sys event 262198 for voice event 86
0009770548 - SPU: 0, Coding Request completed coding=255
0009770548 - CPS: 0, connected_hold gets event coding_ready
0009770548 - SPU: 0, Send release reason=0
0009770548 - SPU:spup_send_release: callref=0000001c
0009770548 - PSU: psup_smc1_release - call ref = 0000001c, reason = 0
0009770548 - SPU: 0, previous tone=255 tone_gen tone=22, to_tele=1, to_net=0
0009770548 - CPS: 0, info->https://www.360docs.net/doc/763353958.html,_rel_tone 22
0009770548 - CPS: 0, connected_hold ==> wait_lss_disc_ack
0009770548 - NMM: Recieved voice event unknown(67) from anon(4294967295), size 132
0009770548 - LFXS: 0, disconnect_wait gets event disconnect
0009770548 - LFXS: 0, disconnect_wait ==> idle
0009770548 - LSS: 0, voice_enable=0
0009770548 - SPU: 0, set coding new_state=IDLE old_state=IDLE
0009770548 - SPU: 0, previous tone=22 tone_gen tone=255, to_tele=1, to_net=0
0009770548 - SPU: 0, 0 tiu ioctl for TIU_VBD_PATH_DISABLE
0009770548 - SPU: 0, tiu ioctl for TIU_VBD_PATH =disable
0009770548 - spuWrapTIU 0: Recvd ioctl command
0009770548 - spuWrapTIU 0: ......ioctl command
0009770548 - spuWrapTIU 0: Received set_re
verse_battery, reverse=0 ...
0009770548 - spuWrapTIU 0: .....set_reverse_battery=0 ...
0009770548 - TIU: 0, set reverse battery=0
0009770548 - CPS: 0, wait_lss_disc_ack gets event lss_idle_ind
0009770548 - CPS: 0, wait_lss_disc_ack ==> idle
0009770548 - SPU: 0, Net set busy=0
0009770548 - SPU: 0, no need to set coding response sent call_ref=1c, tx_idx=0
0009770548 - SPU: 0, coding_ready 255
0009770548 - CPS: 0, voice_enable=0
0009770548 - NMM: Recieved voice event unknown(97) from anon(4294967295), size 4
0009770548 - SSMU: ssmu_nmm_check_media_route med_addr already has the same ip section, remote_addr=5.0.0.0
0009770548 - SSMU: ssmu_nmm_del_media_route check route failure, addr=5.0.217.133
0009770548 - SSMU: ssmu_nmm_check_media_route med_addr already has the same ip section, remote_addr=5.0.0.0
0009770548 - SSMU: ssmu_nmm_del_media_route check route failure, addr=5.0.217.133
0009770548 - SFU: tsg_state_to_sfu_state - converted tsg_state=9 to sfu_state=10
0009770548 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=10, sfu_id=93, apperance= 0
0009770548 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009770548 - SFU: dispatch_gen_msg: msg_id =3
0009770548 - SFU: tsg_state_to_sfu_state - converted tsg_state=0 to sfu_state=2
0009770548 - SFU: call_state_update info: XMC_id=0 tsg_id.port=0 sfu_call_state=2, sfu_id=93, apperance= 0
0009770548 - SFU: sfu_find_call_leg sfu_id=93 on port=0, tele_port=0
0009770548 - SFU: sfu_delete_call_leg XMC_id=0 on port=0, tele_port=0
0009770548 - sfu: init_feature_state on port=0, cw_feature_state = 1 , per_call_state = 1
0009770548 - PSU: psup_state_machine_execute - call ref= 0000001c, state= PSU_STATE_CONNECTED, event= PSU_EVT_SMC1_RELEASE
0009770548 - CMGR: Call ref 0000001C. Call state change. Party ref 00000000
call_state 4, p_call_profile (nil)
0009770548 - CMGR: Call ref 0000001C. Party Rel. Party ref 00000000
initiated 1, release_reason 0, h_party_stats (nil)
0009770548 - PSU: PSU state change - PSU_STATE_CONNECTED -> PSU_STATE_WAIT_SMC2_REL
0009770548 - SPU: 0, Disable voice path
0009770548 - SPU: 0, Recv release resp
0009770548 - ssmu_proc_suppl_ie, Checking for suppl_ie if any, call=0x2ad73298
0009770548 - SSMU: 0, call_id=0, state CONNECTED gets event PSU_RELEASE
0009770548 - SSMU: LOCAL RELEASE on tcid 0
0009770548 - NWIF: nw_close() - closing connection 2.
0009770548 - nw_close() 5.0.217.45:16054 -> 0.0.0.0:0
0009770548 - nwif_fdclear(): fd 23, max fd 25
0009770548 - nw_get_rtp_data(): num_message 1
0009770548 - NWIF: nw_close() - connection number 2 is closed.
0009770548 - NWIF: nw_close() - closing connection 1.
0009770548 - nw_close() 5.0.217.45:16055 -> 0.0.0.0:0
0009770548 - nwif_fdclear(): fd 24, max fd 9
0009770548 - nw_get_rtp_data(): num_message 1
0009770548 - NWIF: nw_close() - connection num
ber 1 is closed.
0009770548 - PSU: psup_smc2_release_resp - call ref = 0000001c
0009770548 - PSU: psup_state_machine_execute - call ref= 0000001c, state= PSU_STATE_WAIT_SMC2_REL, event= PSU_EVT_SMC2_RELEASE_RESP
0009770548 - CMGR: Call ref 0000001C. Party Rel. Party ref 00000001
initiated 0, release_reason 0, h_party_stats (nil)
0009770548 - CMGR: Call ref 0000001C. Call release. release_reason 0
0009770548 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to Disconnecting, reason:14, method=-1
0009770548 - SSMU: 0 CONNECTED ==> RELEASING_NW
0009770548 - Dispatching sys event 262222 for voice event 67
0009770548 - Dispatching sys event 262208 for voice event 97
0009770548 - Dispatching sys event 262224 for voice event 69
0009770548 - Dispatching sys event 262225 for voice event 50
0009770581 - SSMU: SIP MESSAGE <---- RX: SIP/2.0 200 OK
0009770581 - SSMU: SIP MESSAGE <---- RX: to Addr=5.0.217.10:5060, UDP
SIP/2.0 200 OK
Call-ID: ceb680-500d92d-13c2-50029-5d1-5c8a76e1-5d1
Via: SIP/2.0/UDP 5.0.217.45:5058;received=5.0.217.45;branch=z9hG4bK-5eb-1720af-78f64907
To:
From: "Anonymous"
CSeq: 26336 BYE
Contact:
Date: Thu, 23 Jun 2011 07:19:53 GMT
Server: Alcatel-Lucent-HPSS/3.0.3
Content-Length: 0
0009770581 - SSMU CallLegMsgReceivedEvHandler Entered, hAppCallLeg=0x2ad73298, hCallLeg=0xceb680
0009770581 - SSMU rxed Msg msgType=-1, rspStatusCode=200, state=Disconnecting
0009770581 - SSMU: call-leg 0xceb680: p_call 0x2ad73298- RV ReceivedMsg: OtherParam =
0009770581 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009770581 - SSMU: ssmu_proc_diversion_or_historyinfo_header entered!
0009770581 - NMM: Recieved voice event unknown(50) from anon(4294967295), size 136
0009770581 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to Disconnected, reason:15, method=2
0009770581 - SSMU: StateChangeEvHdlr OUTGOING call-leg ceb680 - State changed to Terminated, reason:19, method=-1
0009770581 - SSMU CallLegStateChangedEvHandler TERMINATED call, hAppCallLeg=0x2ad73298, id=0, reason=19
0009770581 - SSMU: 0, call_id=0, state RELEASING_NW gets event NW_DISCONNECTED
0009770581 - SSMU: 0 RELEASING_NW ==> IDLE
0009770581 - SSMU: ssmup_state_goto , ret = 0, state = 9************!
0009770581 - SSMU: ssmup_state_goto: RVSIP_CALL_LEG_STATE_TERMINATED ****
0009770581 - putFreeCBs: freeCBs = 7, pCb = 0x2ad73298
0009770581 - Dispatching sys event 262225 for voice event 50