CISCO CP-8841 an Fritzbox. Ausgehende Anrufe nicht möglich

Hallo,

Ich habe ein Problem seit Längerem ein Problem mit meinem Cisco VOIP Phone. Das Problem ist das eingehende Anrufe klappen aber ausgehende Anrufe funktionnieren nicht (mehr). Es ertönt sofort das Besetztzeichen.

Ich habe damals das Telefon an meiner FritzBox eingerichtet - mit viel Google und Aquis Anleitung hier im Forum. Hab ich auch hinbekommen und alles funktionnierte auch. Irgendwann (wohl nach einem Update der Fritzbox) ging es nicht mehr. Leider kenne ich mich im VOIP Bereich nicht genug aus, weswegen ich wohl auch kaum intelligente Resultate bei google finden kann. Ich wäre dankbar wenn ihr mal Zeit hättet mit drüber zu schauen. wenn Infos fehlen, dann liefere ich die natürlich gerne nach.

Telefon: Cisco CP-8841-3PCC (Software: sip88xx.11-3-3MPP0103-381.loads / Hardware Rev: V43)
Fritzbox : 7490 / Version 7.57

Vielen Dank. Und hier noch die Logs

Log von der Fritzbox

Internettelefonie war nicht erfolgreich. Ursache: NotAcceptableHere (488)

Log vom Telefon:

2691 NOT Mar 21 17:14:11.074847 (10519:10634) voice-http:/admin/log/messages:app_get
2692 NOT Mar 21 17:14:11.074906 (10519:10634) voice-http:app_get:/log/messages admin
2693 NOT Mar 21 17:14:14.172769 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-cf30e439^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: dcd19e31-1428ec5b@192.168.178.45^M
	CSeq: 132 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2694 NOT Mar 21 17:14:14.172825 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=494 
2695 NOT Mar 21 17:14:14.202310 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-cf30e439^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=C8579E7A99D2E911^M  
	Call-ID: dcd19e31-1428ec5b@192.168.178.45^M
	CSeq: 132 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2696 NOT Mar 21 17:14:14.202354 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=344 
2697 NOT Mar 21 17:14:14.202584 (10519:10623) voice-#### sdlg ts event 28
2698 NOT Mar 21 17:14:14.202605 (10519:10623) voice-SUBS Failed 18 28 39
2699 NOT Mar 21 17:14:14.202637 (10519:10623) voice-#### sdlg ts event 32
2700 NOT Mar 21 17:14:23.830477 (861:1007) CDP-cdpRcvPkt(): deviceId is SIP00A2895EB345
2701 NOT Mar 21 17:14:24.202787 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-15badf69^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: b50d6b45-d33f6642@192.168.178.45^M
	CSeq: 7671 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2702 NOT Mar 21 17:14:24.202843 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=495 
2703 NOT Mar 21 17:14:24.222318 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-15badf69^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=8DA2F9E338CAC6CD^M  
	Call-ID: b50d6b45-d33f6642@192.168.178.45^M
	CSeq: 7671 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2704 NOT Mar 21 17:14:24.222362 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=345 
2705 NOT Mar 21 17:14:24.222614 (10519:10623) voice-#### sdlg ts event 28
2706 NOT Mar 21 17:14:24.222636 (10519:10623) voice-SUBS Failed 18 28 39
2707 NOT Mar 21 17:14:24.222665 (10519:10623) voice-#### sdlg ts event 32
2708 NOT Mar 21 17:14:34.222773 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-6576a4e9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: 2e6ffefd-4db29d4@192.168.178.45^M
	CSeq: 57457 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2709 NOT Mar 21 17:14:34.222828 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=495 
2710 NOT Mar 21 17:14:34.242313 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-6576a4e9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=AA09A6BDD99C488E^M  
	Call-ID: 2e6ffefd-4db29d4@192.168.178.45^M
	CSeq: 57457 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2711 NOT Mar 21 17:14:34.242354 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=345 
2712 NOT Mar 21 17:14:34.242593 (10519:10623) voice-#### sdlg ts event 28
2713 NOT Mar 21 17:14:34.242615 (10519:10623) voice-SUBS Failed 18 28 39
2714 NOT Mar 21 17:14:34.242645 (10519:10623) voice-#### sdlg ts event 32
2715 DEB Mar 21 17:14:34.954901 (0:967) syslog-dbus_manager::Sleeping 60 seconds
2716 NOT Mar 21 17:14:35.174316 (10519:10620) voice-fapp-Resync trigger detected. err_retry_attempt:0
2717 NOT Mar 21 17:14:35.174480 (10519:10620) voice-fapp-Provisioning using DHCP..
2718 NOT Mar 21 17:14:35.174503 (10519:10620) voice-FUNCTION:fprv_update, proxy_Config:1
2719 NOT Mar 21 17:14:35.174526 (10519:10620) voice-dhcp_prov_assemble_url All DHCP/DNS-SRV options have been exhausted! Exiting..
2720 NOT Mar 21 17:14:35.174548 (10519:10620) voice-FUNCTION:fprv_update, proxy_Config:1
2721 NOT Mar 21 17:14:35.174560 (10519:10620) voice-fprv_update: eval_profile_dhcp =0  
2722 NOT Mar 21 17:14:35.174571 (10519:10620) voice-FUNCTION: fprv_update, rc_state:6 
2723 NOT Mar 21 17:14:35.176146 (10519:10620) voice-fapp-resync ends since the same rule set is detected
2724 NOT Mar 21 17:14:35.176182 (10519:10620) voice-resync ok.......
2725 NOT Mar 21 17:14:41.393432 (10519:10519) voice-heartbeat 1 =======================================
2726 NOT Mar 21 17:14:41.393815 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_HEARTBEAT argc=1
2727 NOT Mar 21 17:14:41.393858 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_HEARTBEAT_RSP argc=1
2728 NOT Mar 21 17:14:41.394191 (10519:10519) voice-heartbeat rsp 1 =======================================
2729 NOT Mar 21 17:14:44.242777 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-bf08b1b9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: d11e2185-cf5c2cd6@192.168.178.45^M
	CSeq: 56539 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2730 NOT Mar 21 17:14:44.242833 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=496 
2731 NOT Mar 21 17:14:44.262328 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-bf08b1b9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=36179B8BD81AC9ED^M  
	Call-ID: d11e2185-cf5c2cd6@192.168.178.45^M
	CSeq: 56539 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2732 NOT Mar 21 17:14:44.262370 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=346 
2733 NOT Mar 21 17:14:44.262609 (10519:10623) voice-#### sdlg ts event 28
2734 NOT Mar 21 17:14:44.262631 (10519:10623) voice-SUBS Failed 18 28 39
2735 NOT Mar 21 17:14:44.262661 (10519:10623) voice-#### sdlg ts event 32
2736 NOT Mar 21 17:14:52.172311 (750:750) NTP-xmt_timer_cb:  -> fd00::eadf:70ff:fe75:864c
2737 NOT Mar 21 17:14:52.172706 (750:750) NTP-xmt_timer_cb: gap 050000
2738 NOT Mar 21 17:14:52.182581 (750:750) NTP-sock_cb: fd00::eadf:70ff:fe75:864c [fd00::eadf:70ff:fe75:864c]:123
2739 NOT Mar 21 17:14:52.182747 (750:750) NTP-handle_pkt: 48 bytes from fd00::eadf:70ff:fe75:864c fd00::eadf:70ff:fe75:864c
2740 NOT Mar 21 17:14:52.182819 (750:750) NTP-handle_pkt:gettimeofday, tv_dst.tv_sec 1711041292, tv_dst.tv_usec 182808
2741 NOT Mar 21 17:14:52.182939 (750:750) NTP-2024-03-21 17:14:52.182808 (+0000) +0.00882 +/- 0.047613 fd00::eadf:70ff:fe75:864c s3 no-leap
2742 NOT Mar 21 17:14:52.191853 (750:750) NTP-4 NTP and 0 name queries pending
2743 NOT Mar 21 17:14:52.191909 (750:750) NTP-4 NTP and 0 name queries pending
2744 NOT Mar 21 17:14:52.232252 (750:750) NTP-xmt_timer_cb:  -> 192.168.178.1
2745 NOT Mar 21 17:14:52.232567 (750:750) NTP-xmt_timer_cb: next 64 seconds
2746 NOT Mar 21 17:14:52.242043 (750:750) NTP-sock_cb: 192.168.178.1 192.168.178.1:123
2747 NOT Mar 21 17:14:52.242253 (750:750) NTP-sock_cb: not from active source, ignore
2748 NOT Mar 21 17:14:52.242338 (750:750) NTP-4 NTP and 0 name queries pending
2749 NOT Mar 21 17:14:52.242394 (750:750) NTP-4 NTP and 0 name queries pending
2750 NOT Mar 21 17:14:54.271590 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5b6ed2d9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: 19fe57dd-9105ae48@192.168.178.45^M
	CSeq: 47669 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2751 NOT Mar 21 17:14:54.271645 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=496 
2752 NOT Mar 21 17:14:54.301133 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5b6ed2d9^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=7529FDD4D720B03E^M  
	Call-ID: 19fe57dd-9105ae48@192.168.178.45^M
	CSeq: 47669 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2753 NOT Mar 21 17:14:54.301175 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=346 
2754 NOT Mar 21 17:14:54.301416 (10519:10623) voice-#### sdlg ts event 28
2755 NOT Mar 21 17:14:54.301436 (10519:10623) voice-SUBS Failed 18 28 39
2756 NOT Mar 21 17:14:54.301465 (10519:10623) voice-#### sdlg ts event 32
2757 NOT Mar 21 17:14:59.442569 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_NUM?keypress=2>
2758 NOT Mar 21 17:14:59.442728 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallEditDial?keypress=2>
2759 NOT Mar 21 17:14:59.448342 (10528:10528) mphone-CSEngine_handleInternalURI(): Info: csscript: eval Internal URI <Application_DialScreenStartTimer>
2760 NOT Mar 21 17:14:59.562649 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_NUM_UP?refID=0000&name=&number=2&keypress=2>
2761 NOT Mar 21 17:15:01.252550 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_GoBack?keypress=176>
2762 NOT Mar 21 17:15:01.253086 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_HomeCheck>
2763 NOT Mar 21 17:15:01.872574 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_NUM?keypress=6>
2764 NOT Mar 21 17:15:01.872735 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallEditDial?keypress=6>
2765 NOT Mar 21 17:15:01.878132 (10528:10528) mphone-CSEngine_handleInternalURI(): Info: csscript: eval Internal URI <Application_DialScreenStartTimer>
2766 NOT Mar 21 17:15:01.992648 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_NUM_UP?refID=0000&name=&number=4&keypress=4>
...REMOVED...
2786 NOT Mar 21 17:15:04.274709 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_DialReportDigit?refID=0000&name=&number=123456&changeMode=keypress&changeData=1>
2787 NOT Mar 21 17:15:04.301598 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5814d51^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: 19988ef9-4a9d85c7@192.168.178.45^M
	CSeq: 3968 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2788 NOT Mar 21 17:15:04.301655 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=494 
2789 NOT Mar 21 17:15:04.331125 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5814d51^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80A8E475C8F84E5D^M  
	Call-ID: 19988ef9-4a9d85c7@192.168.178.45^M
	CSeq: 3968 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2790 NOT Mar 21 17:15:04.331170 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=344 
2791 NOT Mar 21 17:15:04.331410 (10519:10623) voice-#### sdlg ts event 28
2792 NOT Mar 21 17:15:04.331429 (10519:10623) voice-SUBS Failed 18 28 39
2793 NOT Mar 21 17:15:04.331460 (10519:10623) voice-#### sdlg ts event 32
2794 NOT Mar 21 17:15:04.392636 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_NUM_UP?refID=0000&name=&number=123456&keypress=1>
...REMOVED...
2798 NOT Mar 21 17:15:05.564632 (10528:10528) mphone-CSEngine_handleInternalURI(): Info: csscript: eval Internal URI <Application_Dial>
2799 NOT Mar 21 17:15:05.564922 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Dial?refID=0000&name=&number=123456789>
2800 NOT Mar 21 17:15:05.565113 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_DIAL argc=6
2801 NOT Mar 21 17:15:05.565412 (10519:10519) voice-CControl_registerFd: <FRZ> New FD registered: -884, timeout:2000 
2802 NOT Mar 21 17:15:05.565742 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_HomeCheck>
2803 NOT Mar 21 17:15:05.571233 (10519:10623) voice-CC_eventProc(event=4098(CC_EV_GUI_TRY_NEW_CALL2), lid=0, par=1, par2=0x6240a4)
2804 NOT Mar 21 17:15:05.571331 (10519:10623) voice-[CCTRL]Obtain Call Obj(109) 0xb593d638(0:0)<0:0>
2805 WRN Mar 21 17:15:05.571363 (10519:10623) voice-[WLAN_API-wlanApiConnectStatusGet] Error getting status
2806 NOT Mar 21 17:15:05.571408 (10519:10623) voice-LOG_NEW_CALL_STATE {"ref_id": "0000", "old_state": "CC_CST_IDLE", "new_state": "CC_CST_DIALING"}  
2807 NOT Mar 21 17:15:05.571426 (10519:10623) voice-VDBUS.CallControlIPC_SignalCallStateChanged Signal call state changed for line (0) call (0) and new state is (6)
2808 NOT Mar 21 17:15:05.571620 (10519:10623) voice-[CC]dialcall, ucState: 6 bSkipDialPlan:0
2809 NOT Mar 21 17:15:05.572488 (10519:10623) voice-initialized codec-list (codec#=12)
2810 NOT Mar 21 17:15:05.572522 (10519:10623) voice-CC_getVideoCodecList numcodec=0
2811 NOT Mar 21 17:15:05.572597 (10519:10623) voice-LOG_NEW_CALL_STATE {"ref_id": "0000", "old_state": "CC_CST_DIALING", "new_state": "CC_CST_CALLING"}  
2812 NOT Mar 21 17:15:05.572630 (10519:10623) voice-VDBUS.CallControlIPC_SignalCallStateChanged Signal call state changed for line (0) call (0) and new state is (8)
2813 NOT Mar 21 17:15:05.581000 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2814 NOT Mar 21 17:15:05.581053 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2815 NOT Mar 21 17:15:05.581098 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2816 NOT Mar 21 17:15:05.582373 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2817 NOT Mar 21 17:15:05.582428 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2818 NOT Mar 21 17:15:05.582593 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2819 NOT Mar 21 17:15:05.582618 (10502:10502) ms-APATH.ms_setOutputAudioPath No active streams, exiting
2820 NOT Mar 21 17:15:05.582717 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2821 NOT Mar 21 17:15:05.583668 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2822 NOT Mar 21 17:15:05.583715 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2823 NOT Mar 21 17:15:05.583731 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2824 NOT Mar 21 17:15:05.593194 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2825 NOT Mar 21 17:15:05.593248 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2826 NOT Mar 21 17:15:05.593266 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2827 NOT Mar 21 17:15:05.593542 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2828 NOT Mar 21 17:15:05.593571 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2829 NOT Mar 21 17:15:05.593585 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2830 NOT Mar 21 17:15:05.620745 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_DIALING argc=22
2831 NOT Mar 21 17:15:05.622497 (10528:10528) mphone-[CCallAppn_gotoCall]: pCall1->callState=9  pCall1->castState=0
2832 NOT Mar 21 17:15:05.623130 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession>
2833 NOT Mar 21 17:15:05.627018 (10519:10623) voice-[SIP]: SIP_lineCcCmdProc cmd=(CC_CMD_NEWCALL), par=(4), ucCnt=(4), cc->ucId=(0), sipline->ucId=(0), par2=(0xb593d65c).
2834 NOT Mar 21 17:15:05.630042 (10519:10623) voice-Calling:12345679@192.168.178.1:0
2835 NOT Mar 21 17:15:05.630197 (10519:10623) voice-SIP_sessCreate: SIP_SESSION size 189364 bytes
2836 ERR Mar 21 17:15:05.630437 (10519:10623) voice-SIP_sessCreate: ICE: Video support <No>
2837 NOT Mar 21 17:15:05.630652 (10502:10502) ms-MSAPI.ms_getNextPort : port 0
2838 NOT Mar 21 17:15:05.630849 (10519:10623) voice-SIP_initSdpAttr, ip6: e8070120:ef03c2:ffe4263e:7ac768fe, ip4: 2db2a8c0
2839 NOT Mar 21 17:15:05.630924 (10519:10623) voice-SIP_initSdpAttr, dual mode, afAltc1: [192.168.178.45:16386], afAltc2: [2001:7e8:c203:ef00:3e26:e4ff:fe68:c77a:16386]
2840 ERR Mar 21 17:15:05.630970 (10519:10623) voice-aud_wb.c::AUDWB_configureRtcp rtcp interval is 0
2841 NOT Mar 21 17:15:05.631043 (10502:10502) ms-FEATUREMGR.ms_enableFeature ms_enableFeature: [configNumber=0][enabled=0]
2842 WRN Mar 21 17:15:05.631090 (10502:10502) ms-FEATUREMGR.ftm_enableFeature RTCP is set DISABLED
2843 NOT Mar 21 17:15:05.631238 (10502:10502) ms-FEATUREMGR.ms_enableFeature ms_enableFeature: [configNumber=1][enabled=0]
2844 WRN Mar 21 17:15:05.631259 (10502:10502) ms-FEATUREMGR.ftm_enableFeature RTCP INTVL is set DISABLED
2845 NOT Mar 21 17:15:05.631343 (10502:10502) ms-FEATUREMGR.ms_enableFeature ms_enableFeature: [configNumber=12][enabled=0]
2846 NOT Mar 21 17:15:05.631436 (10502:10502) ms-FEATUREMGR.ms_enableFeature ms_enableFeature: [configNumber=14][enabled=0]
2847 NOT Mar 21 17:15:05.631717 (10502:10502) ms-APATH.ms_setMicrophoneGain: path = 262, gain = 0
2848 NOT Mar 21 17:15:05.632186 (10502:10502) ms-APATH.ms_setSidetoneLevel path = 0, level = 2
2849 NOT Mar 21 17:15:05.632531 (10519:10623) voice-alloc ipv4 aud call: 192.168.178.45
2850 NOT Mar 21 17:15:05.632648 (10502:10502) ms-RTPSESSION.ms_createRTPSession enter [rxpm=0xbee48594] [txpm=(nil)]
2851 NOT Mar 21 17:15:05.632682 (10502:10502) ms-RTPSESSION.createRTPSession media [ipv4=192.168.178.45][port=16386][interface=NULL][mediatype=6][relayee=0][groupid=0][callid=0]
2852 NOT Mar 21 17:15:05.632698 (10502:10502) ms-RTPSESSION.createRTPSession receive [dynamicRTPPayloadType=9][hootNumTalkers=0][packetPeriod=20]
2853 NOT Mar 21 17:15:05.632721 (10502:10502) ms-SESSIONMGR.sm_setSessionType[A:6] [media=6][type=1]
2854 NOT Mar 21 17:15:05.632761 (10502:10502) ms-RTPSESSION.setRTPDualSessionBlk[A:6] [audio rx security=0][dynamicPayloadType=9][fecPayloadType=0][hootNumTalkers=0][dtmfPayloadType=101][pktperiod=20]
2855 NOT Mar 21 17:15:05.632782 (10502:10502) ms-RTPSESSION.ms_createRTPSession[A:6] exit
2856 NOT Mar 21 17:15:05.632795 (10502:10502) ms-SESSIONMGR.ms_parentSession[A:6] [client=3]
2857 NOT Mar 21 17:15:05.632963 (10519:10623) voice-ICE-DBG START_RTPRX2: RTPRX getting called  STATE 
2858 NOT Mar 21 17:15:05.633017 (10519:10623) voice-[CC_commonCodec:354] lid:0 ncLocal:9 cLocal[0]:9 ncRemote:0 cRemote[0]:0 bRemotePrefCodec:0 bIsRemoteOfferSdp:0 
2859 NOT Mar 21 17:15:05.633066 (10519:10623) voice-starting RTP Rx for session 6: 9
2860 NOT Mar 21 17:15:05.633132 (10502:10502) ms-RTPSESSION.ms_addRTPRxParamNoTx[A:6] enter
2861 NOT Mar 21 17:15:05.633151 (10502:10502) ms-RTPSESSION.ms_addRTPRxParamNoTx[A:6] rx [mediaType(codec)=6][hootNumTalkers=0][dtmfPayloadType=101][pktperiod=20][security=0][dynamicPayloadType=9][fecPayloadType=0]
2862 NOT Mar 21 17:15:05.633171 (10502:10502) ms-RTPSESSION.ms_addRTPRxParamNoTx[A:6] exit [status=0]
2863 NOT Mar 21 17:15:05.633281 (10502:10502) ms-RTPSESSION.ms_allocateRTPSessionRx[A:6] enter
2864 NOT Mar 21 17:15:05.633316 (10502:10502) ms-SESSIONMGR.ms_realizeSession[A:6] enter
2865 NOT Mar 21 17:15:05.633335 (10502:10502) ms-RTPSESSION.realizeRTPDualSession[A:6] [ptype=6][rx_src=5][rx_sink=6][tx_src=7][tx_sink=8]
2866 NOT Mar 21 17:15:05.633496 (10502:10502) ms-SELECTMGR.slm_getFileDescriptorBlk Slot allocated [idx=1][fd=38][reserved=1][count=0]
2867 NOT Mar 21 17:15:05.633536 (10502:10502) ms-STREAMMGR.stm_initMelementBlk[A:6] [meh=5][type=1][src/sink accessType=0][accessHandle=0x26]
2868 NOT Mar 21 17:15:05.633562 (10502:10502) ms-STREAMMGR.stm_initMelementBlk[A:6] [meh=6][type=2][src/sink accessType=1][accessHandle=0x0]
2869 NOT Mar 21 17:15:05.633579 (10502:10502) ms-STREAMMGR.stm_initMelementBlk[A:6] [meh=7][type=3][src/sink accessType=1][accessHandle=0x0]
2870 NOT Mar 21 17:15:05.633592 (10502:10502) ms-STREAMMGR.stm_initMelementBlk[A:6] [meh=8][type=4][src/sink accessType=0][accessHandle=0x26]
2871 NOT Mar 21 17:15:05.633608 (10502:10502) ms-SESSIONMGR.ms_realizeSession[A:6] exit [status=0][errno=0]
2872 NOT Mar 21 17:15:05.633624 (10502:10502) ms-DSPMGR.dm_getCodecResource Subtracted for CODEC [idx=4][name=G.722][direction=0][cost=10][old budget=100]
2873 NOT Mar 21 17:15:05.634222 (10502:10502) ms-RTPSESSION.ms_allocateRTPSessionRx[A:6] exit [status=0]
2874 NOT Mar 21 17:15:05.634395 (10502:10502) ms-RTPSESSION.ms_startRTPSessionRx[A:6] enter
2875 NOT Mar 21 17:15:05.636129 (10528:10528) mphone-CSEngine_handleInternalURI(): Info: csscript: eval Internal URI <Application_CallSession_OnCurrent>
2876 NOT Mar 21 17:15:05.636448 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession_OnCurrent?refID=0000>
2877 NOT Mar 21 17:15:05.636721 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession_OnActive>
2878 NOT Mar 21 17:15:05.652575 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_DIALING argc=22
2879 NOT Mar 21 17:15:05.655428 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_TRANSFER argc=2
2880 NOT Mar 21 17:15:05.658153 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_LOGCALL argc=3
2881 NOT Mar 21 17:15:05.660809 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_SYSTEM_AUDIO_PATH argc=3
2882 NOT Mar 21 17:15:05.661342 (10528:10528) mphone-CProto_sendPDU(): sendPDU: header: Fd=18 id=<2057> code=<103>, nLen=20, expectedlen=20
2883 NOT Mar 21 17:15:05.681672 ==vocoder_session_aud_pcm_start== channel = 4; session mode: 0x8; session voice_fd = 0: requested mode = 8
2884 NOT Mar 21 17:15:05.689246 (10502:10502) ms-DSPMGR.dm_startAudioRx omx_startComp [component=x200][return=0]
2885 NOT Mar 21 17:15:05.689396 (10502:10502) ms-AOMXCLIENTAPI.omx_startSinkPlayout omx_startSinkPlayout
2886 NOT Mar 21 17:15:05.689427 (10502:10502) ms-DSPMGR.dm_startAudioRx omx_startSinkPlayout on [component=x200][return=0]
2887 NOT Mar 21 17:15:05.689449 (10502:10502) ms-CALLBKMGR.cb_mediaRemoteAdded [callId=0][seh=6]
2888 NOT Mar 21 17:15:05.689537 (10502:10502) ms-SELECTMGR.slm_selectFdInit [idx=1][fd=38][sdata=5][reserved=1][count=1][func=0x26338][demuxfunc=0x26418][proxy func=(nil)]
2889 NOT Mar 21 17:15:05.689628 (10502:10606) ms-SELECTMGR.slm_readIPC [fd=16] Select thread got message
2890 DEB Mar 21 17:15:05.689763 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 25
2891 DEB Mar 21 17:15:05.689799 (10539:10617) voice_feedback-libms: libmsAEventThread - Received Remote Media Added, sessionid x6
2892 NOT Mar 21 17:15:05.689898 (10502:10502) ms-RTPSESSION.ms_startRTPSessionRx[A:6] START RX [stream=5][mediaType(codec)=6][pkt size=20][P-IPv4=192.168.178.45][Port=16386][groupid=0][callid=0]
2893 NOT Mar 21 17:15:05.689928 (10502:10502) ms-RTPSESSION.ms_startRTPSessionRx[A:6] exit status=0
2894 NOT Mar 21 17:15:05.690045 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2895 NOT Mar 21 17:15:05.690070 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2896 NOT Mar 21 17:15:05.690083 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2897 NOT Mar 21 17:15:05.690139 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2898 NOT Mar 21 17:15:05.690155 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2899 NOT Mar 21 17:15:05.690205 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2900 NOT Mar 21 17:15:05.692716 (10502:21344) ms-RTPMGR.rtpmgr_getNextAudioFrameFromJB[A:6] Out [flag=0x100][redDepthIn/Out=0][frameDur=10][ringFlag=0][ttyFlag=0][ttyAllow=0][vad=256][blockttyFl=0][singleFl=0][blockFl=0][considFl=0]
2901 NOT Mar 21 17:15:05.693262 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2902 NOT Mar 21 17:15:05.693601 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2903 NOT Mar 21 17:15:05.693634 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2904 NOT Mar 21 17:15:05.693648 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2905 NOT Mar 21 17:15:05.694341 (10519:10623) voice-SIP_cryptoOffer2: audio crypto suite 0 and 1
2906 NOT Mar 21 17:15:05.694384 (10519:10623) voice-SIP_cryptoOfferForMline: create new crypto
2907 NOT Mar 21 17:15:05.694690 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 1, crypto suite 1
2908 NOT Mar 21 17:15:05.694720 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 2, crypto suite 0
2909 NOT Mar 21 17:15:05.694826 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 1, crypto suite 1
2910 NOT Mar 21 17:15:05.694845 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 2, crypto suite 0
2911 NOT Mar 21 17:15:05.694932 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  INVITE sip:123456789@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-34d3baf1^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:123465789@192.168.178.1>^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 101 INVITE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 240^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 1252^M
	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE^M
	Supported: replaces, sec-agree^M
	Content-Type: application/sdp^M
	^M
	v=0^M
	o=- 60866161 60866161 IN IP4 192.168.178.45^M
	s=-^M
	c=IN IP4 192.168.178.45^M
	t=0 0^M
	m=audio 16386 RTP/SAVP 9 0 8 18 97 105 96 103 99 101 107 108^M
	a=rtpmap:9 G722/8000^M
	a=rtpmap:0 PCMU/8000^M
	a=rtpmap:8 PCMA/8000^M
	a=rtpmap:18 G729a/8000^M
	a=fmtp:18 annexb=yes^M
	a=rtpmap:97 iLBC/8000^M
	a=fmtp:97 mode=20^M
	a=rtpmap:105 iLBC/8000^M
	a=fmtp:105 mode=30^M
	a=rtpmap:96 AMR-WB/16000
2912 NOT Mar 21 17:15:05.694949 (10519:10623) voice-^M
	a=fmtp:96 channels=1;octet-align=0;mode-change-capability=2;mode-change-neighbor=0;crc=0;robust-sorting=0;interleaving=0;max-red=0^M
	a=rtpmap:103 AMR-WB/16000^M
	a=fmtp:103 channels=1;octet-align=1;mode-change-capability=2;mode-change-neighbor=0;crc=0;robust-sorting=0;interleaving=0;max-red=0^M
	a=rtpmap:99 OPUS/48000/2^M
	a=fmtp:99 maxplaybackrate=16000;sprop-maxcapturerate=16000;maxaveragebitrate=64000;stereo=0;sprop-stereo=0;usedtx=0^M
	a=rtpmap:101 telephone-event/8000^M
	a=fmtp:101 0-15^M
	a=rtpmap:107 telephone-event/16000^M
	a=fmtp:107 0-15^M
	a=rtpmap:108 telephone-event/48000^M
	a=fmtp:108 0-15^M
	a=ptime:20^M
	a=sendrecv^M
	a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:...
	a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:...
	a=altc:1 IP4 192.168.178.45 16386^M
	a=altc:2 IP6 2001:7e8:c203:ef00:3e26:e4ff:fe68:c77a 16386^M
2913 NOT Mar 21 17:15:05.694961 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=1854 
2914 NOT Mar 21 17:15:05.702443 (10519:10519) voice-CRemoteRepoProto_serviceApp(): request code = 103
2915 NOT Mar 21 17:15:05.702579 (10519:10519) voice-CProto_readDataToPayLoad(): Class Name: <VolumeSetting> Field Count: <15>
2916 NOT Mar 21 17:15:05.702716 (10519:10519) voice-CProto_sendPDU(): sendPDU: header: Fd=36 id=<2057> code=<104>, nLen=20, expectedlen=20
2917 NOT Mar 21 17:15:05.702755 (10519:10519) voice-CControl_service: CControl_deRegisterFd remove the entry:-884
2918 NOT Mar 21 17:15:05.703153 (10528:10528) mphone-CProto_decodePayLoadToDataObject(): Class Name: <VolumeSetting> Field Count: <15>
2919 NOT Mar 21 17:15:05.703368 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SET_VOLUME argc=3
2920 NOT Mar 21 17:15:05.703644 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2921 NOT Mar 21 17:15:05.703691 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2922 NOT Mar 21 17:15:05.703708 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2923 NOT Mar 21 17:15:05.703916 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2924 NOT Mar 21 17:15:05.703944 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2925 NOT Mar 21 17:15:05.704000 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2926 NOT Mar 21 17:15:05.704273 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SET_VOLUME argc=3
2927 NOT Mar 21 17:15:05.704369 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SET_VOLUME argc=3
2928 NOT Mar 21 17:15:05.704427 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SET_VOLUME argc=3
2929 NOT Mar 21 17:15:05.707261 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2930 NOT Mar 21 17:15:05.707654 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2931 NOT Mar 21 17:15:05.707689 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2932 NOT Mar 21 17:15:05.707704 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2933 NOT Mar 21 17:15:05.741144 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 401 Unauthorized^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-34d3baf1^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:123456789@192.168.178.1>;tag=6A5B94CC8BADE417^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 101 INVITE^M
	WWW-Authenticate: Digest realm="fritz.box", nonce="E28D188D86EB03BC"^M  
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
2934 NOT Mar 21 17:15:05.741191 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=410 
2935 NOT Mar 21 17:15:05.741600 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  ACK sip:123456789@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-34d3baf1^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:12345679@192.168.178.1>;tag=6A5B94CC8BADE417^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 101 ACK^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2936 NOT Mar 21 17:15:05.741632 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=466 
2937 NOT Mar 21 17:15:05.742330 (10519:10623) voice-SIP_cryptoOffer2: audio crypto suite 0 and 1
2938 NOT Mar 21 17:15:05.742372 (10519:10623) voice-SIP_cryptoOfferForMline: use existing crypto
2939 NOT Mar 21 17:15:05.742786 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 1, crypto suite 1
2940 NOT Mar 21 17:15:05.742820 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 2, crypto suite 0
2941 NOT Mar 21 17:15:05.742934 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 1, crypto suite 1
2942 NOT Mar 21 17:15:05.742952 (10519:10623) voice-sdpaudio_renderAudioMline: send crypto 2, crypto suite 0
2943 NOT Mar 21 17:15:05.743043 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  INVITE sip:123456798@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-89536ed5^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:12345679@192.168.178.1>^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 102 INVITE^M
	Max-Forwards: 70^M
	Authorization: Digest username="ciscoBuro",realm="fritz.box",nonce="E28D188D86EB03BC",uri="sip:123456@192.168.178.1",algorithm=MD5,response="563fd03c08a60308d0aa6ce416791f63"^M  
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 240^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 1252^M
	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE^M
	Supported: replaces, sec-agree^M
	Content-Type: application/sdp^M
	^M
	v=0^M
	o=- 60866161 60866161 IN IP4 192.168.178.45^M
	s=-^M
	c=IN IP4 192.168.178.45^M
	t=0 0^M
	m=audio 16386 RTP/SAVP 9 0 8 18 97 105 96 103 99 101 107 108^M
	a=rtpmap:9 G722/8000^M
	a=rtpmap:0 PCMU/8000^M
2944 NOT Mar 21 17:15:05.743060 (10519:10623) voice-
	a=rtpmap:8 PCMA/8000^M
	a=rtpmap:18 G729a/8000^M
	a=fmtp:18 annexb=yes^M
	a=rtpmap:97 iLBC/8000^M
	a=fmtp:97 mode=20^M
	a=rtpmap:105 iLBC/8000^M
	a=fmtp:105 mode=30^M
	a=rtpmap:96 AMR-WB/16000^M
	a=fmtp:96 channels=1;octet-align=0;mode-change-capability=2;mode-change-neighbor=0;crc=0;robust-sorting=0;interleaving=0;max-red=0^M
	a=rtpmap:103 AMR-WB/16000^M
	a=fmtp:103 channels=1;octet-align=1;mode-change-capability=2;mode-change-neighbor=0;crc=0;robust-sorting=0;interleaving=0;max-red=0^M
	a=rtpmap:99 OPUS/48000/2^M
	a=fmtp:99 maxplaybackrate=16000;sprop-maxcapturerate=16000;maxaveragebitrate=64000;stereo=0;sprop-stereo=0;usedtx=0^M
	a=rtpmap:101 telephone-event/8000^M
	a=fmtp:101 0-15^M
	a=rtpmap:107 telephone-event/16000^M
	a=fmtp:107 0-15^M
	a=rtpmap:108 telephone-event/48000^M
	a=fmtp:108 0-15^M
	a=ptime:20^M
	a=sendrecv^M
	a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:...
	a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:...
	a=altc:1 IP4 192.168.178.45 16386^M
	a=altc:2 IP6 2001:7e8:c203:ef00:3e26:e4ff:fe68:c77a 16386^M
2945 NOT Mar 21 17:15:05.743072 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=2033 
2946 NOT Mar 21 17:15:05.752504 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2947 NOT Mar 21 17:15:05.752562 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2948 NOT Mar 21 17:15:05.752584 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2949 NOT Mar 21 17:15:05.752770 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2950 NOT Mar 21 17:15:05.752798 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2951 NOT Mar 21 17:15:05.752896 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2952 NOT Mar 21 17:15:05.755482 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2953 NOT Mar 21 17:15:05.755735 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2954 NOT Mar 21 17:15:05.755765 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2955 NOT Mar 21 17:15:05.755779 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2956 NOT Mar 21 17:15:05.755909 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2957 NOT Mar 21 17:15:05.755930 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2958 NOT Mar 21 17:15:05.755945 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2959 NOT Mar 21 17:15:05.756101 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2960 NOT Mar 21 17:15:05.756124 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2961 NOT Mar 21 17:15:05.756174 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2962 NOT Mar 21 17:15:05.759234 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2963 NOT Mar 21 17:15:05.760613 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2964 NOT Mar 21 17:15:05.760646 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2965 NOT Mar 21 17:15:05.760660 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2966 NOT Mar 21 17:15:05.760865 (10502:10502) ms-APATH.ms_setMute [setting mute on]
2967 NOT Mar 21 17:15:05.760891 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
2968 NOT Mar 21 17:15:05.760904 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
2969 NOT Mar 21 17:15:05.761038 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 262]
2970 NOT Mar 21 17:15:05.761080 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
2971 NOT Mar 21 17:15:05.761308 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 7][volume=11]
2972 NOT Mar 21 17:15:05.765404 (10502:10502) ms-APATH.ms_setSideTone disable side tone
2973 NOT Mar 21 17:15:05.765585 (10502:10502) ms-APATH.ms_setMute [setting mute off]
2974 NOT Mar 21 17:15:05.765610 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
2975 NOT Mar 21 17:15:05.765624 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
2976 NOT Mar 21 17:15:05.871256 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 488 Not Acceptable Here^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-89536ed5^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:123456789@192.168.178.1>;tag=09CC36CAACD3AED6^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 102 INVITE^M
	Warning: 399 0.0.0.0 "no fitting codec"^M  
	User-Agent: FRITZ!OS^M
	Content-Type: application/sdp^M
	Content-Length:   443^M
	^M
	v=0^M
	o=user 7641570 7641570 IN IP4 192.168.178.1^M
	s=call^M
	c=IN IP4 192.168.178.1^M
	t=0 0^M
	m=audio 7082 RTP/AVP 9 8 0 2 102 100 99 101 97 18 120 121^M
	a=sendrecv^M
	a=rtpmap:2 G726-32/8000^M
	a=rtpmap:102 G726-32/8000^M
	a=rtpmap:100 G726-40/8000^M
	a=rtpmap:99 G726-24/8000^M
	a=rtpmap:101 telephone-event/8000^M
	a=fmtp:101 0-15^M
	a=rtpmap:97 iLBC/8000^M
	a=fmtp:97 mode=30^M
	a=fmtp:18 annexb=no^M
	a=rtpmap:120 PCMA/16000^M
	a=rtpmap:121 PCMU/16000^M
	a=rtcp:7083^M
2977 NOT Mar 21 17:15:05.871308 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=866 
2978 NOT Mar 21 17:15:05.871635 (10519:10623) voice-SIP_parseSdp: Processing audio mline
2979 NOT Mar 21 17:15:05.871679 (10519:10623) voice-SDP RTPMAP 102 --> 255
2980 NOT Mar 21 17:15:05.871702 (10519:10623) voice-SDP RTPMAP 100 --> 255
2981 NOT Mar 21 17:15:05.871719 (10519:10623) voice-SDP RTPMAP 99 --> 255
2982 NOT Mar 21 17:15:05.871733 (10519:10623) voice-SDP RTPMAP 101 --> 140
2983 NOT Mar 21 17:15:05.871748 (10519:10623) voice-SDP RTPMAP 97 --> 129
2984 NOT Mar 21 17:15:05.871771 (10519:10623) voice-SDP RTPMAP 120 --> 255
2985 NOT Mar 21 17:15:05.871786 (10519:10623) voice-SDP RTPMAP 121 --> 255
2986 NOT Mar 21 17:15:05.872011 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  ACK sip:123456789@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-89536ed5^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=cc6b3dc6f7a35de2o0^M  
	To: "myHandy" <sip:12345679@192.168.178.1>;tag=09CC36CAACD3AED6^M  
	Call-ID: df8e5c7d-254769e@192.168.178.45^M
	CSeq: 102 ACK^M
	Max-Forwards: 70^M
	Authorization: Digest username="ciscoBuro",realm="fritz.box",nonce="E28D188D86EB03BC",uri="sip:123456789@192.168.178.1",algorithm=MD5,response="563fd03c08a60308d0aa6ce416791f63"^M  
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
2987 NOT Mar 21 17:15:05.872039 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=645 
2988 NOT Mar 21 17:15:05.872323 (10502:10502) ms-MS.statsMgr_getSessionPegs[A:6] enter
2989 NOT Mar 21 17:15:05.872366 (10502:10502) ms-MS.statsMgr_getSessionPegs[A:6] gathering active session stats
2990 NOT Mar 21 17:15:05.872398 (10502:10502) ms-MS.statsMgr_getSessionRxPegs[A:6] gathering active audio rx stream stats
2991 NOT Mar 21 17:15:05.872423 (10502:10502) ms-RTCPMGR.rtcpm_getSr[A:6] TX stream state not connected [ingress=7][state=0]
2992 NOT Mar 21 17:15:05.872439 (10502:10502) ms-RTCPMGR.rtcpm_getSr[A:6] TBD*** fill pTxStat with 0 for the time being
2993 NOT Mar 21 17:15:05.872466 (10502:10502) ms-MS.statsMgr_getSessionPegs[A:6] exit
2994 NOT Mar 21 17:15:05.873168 (10502:10502) ms-MS.statsMgr_getSessionMetrics[A:6] enter
2995 NOT Mar 21 17:15:05.873207 (10502:10502) ms-MS.statsMgr_getSessionMetrics[A:6] gathering active session metrics
2996 NOT Mar 21 17:15:05.873225 (10502:10502) ms-MS.statsMgr_getSessionMetrics[A:6] exit (0)
2997 ERR Mar 21 17:15:05.873372 (10502:10502) ms-_msDispatch: msapi function error: retv -1, errno 0, len 20, MSAPI opcode = 196(undefined)
2998 NOT Mar 21 17:15:05.873444 (10502:10502) ms-SESSIONMGR.ms_closeSession[A:6] enter
2999 NOT Mar 21 17:15:05.873462 (10502:10502) ms-RTPSESSION.ms_deallocateRTPSessionRx[A:6] enter
3000 NOT Mar 21 17:15:05.873476 (10502:10502) ms-RTPSESSION.ms_stopRTPSessionRx[A:6] enter [stream=5]
3001 NOT Mar 21 17:15:05.873492 (10502:10502) ms-SELECTMGR.slm_selectFdClose_locked Socket marked inactive [idx=1][sdata=5][fd=38][reserved=1][count=0]
3002 NOT Mar 21 17:15:05.873532 (10502:10606) ms-SELECTMGR.slm_readIPC [fd=16] Select thread got message
3003 NOT Mar 21 17:15:05.873620 (10502:10502) ms-MS.statsMgr_getSessionRxPegs[A:6] gathering active audio rx stream stats
3004 NOT Mar 21 17:15:05.873648 (10502:10502) ms-aomxSink_stopPort: enter (for port 2)......
3005 NOT Mar 21 17:15:05.896752 (10519:10620) voice-fapp-line_active - is_a_call_in_progress() is 1
3006 NOT Mar 21 17:15:05.904682 (10502:10502) ms-aomxSink_stopPort: tsem_down_timeout now...
3007 NOT Mar 21 17:15:05.904729 (10502:10502) ms-aomxSink_stopPort: ......exit
3008 NOT Mar 21 17:15:05.904742 (10502:10502) ms-AOMXCLIENTAPI.omx_stopSinkPlayout stopSinkPlayout [compType=2]
3009 NOT Mar 21 17:15:05.904764 (10502:10502) ms-DSPMGR.dm_stopAudioRx omx_stopSinkPlayout on [component=x200][retVal=0]
3010 NOT Mar 21 17:15:05.904781 (10502:10502) ms-DSPMGR.dm_stopAudioRx  omx_disconnectComponent on [component=x200][retVal=0]
3011 NOT Mar 21 17:15:05.909384 (10502:21344) ms-AOMXCLIENTAPI.decoderEmptyBufferDone decoder port 0 is being stopped(1)
3012 NOT Mar 21 17:15:05.910011 (10502:10502) ms-AlsaDevice.alsa_close enter
3013 NOT Mar 21 17:15:05.911217 (10502:10502) ms-AlsaDevice.alsa_close set Playback as DEVICE_PCM_CLOSED
3014 NOT Mar 21 17:15:05.911259 (10502:10502) ms-CALLBKMGR.cb_mediaRemoteRemoved [callId=0][seh=6]
3015 NOT Mar 21 17:15:05.911334 (10502:10502) ms-RTPSESSION.ms_stopRTPSessionRx[A:6] exit [status=0]
3016 NOT Mar 21 17:15:05.911355 (10502:10502) ms-DSPMGR.dm_freeCodecResource DSPMGR Added back for CODEC [idx=4][name=G.722][direction=0][cost=10][old budget=90]
3017 NOT Mar 21 17:15:05.911429 (10502:10502) ms-JB.mgrJitterBufferRecordJitterDone[A:6] j=0.000 n=0 T=0.277 
3018 NOT Mar 21 17:15:05.911464 (10502:10502) ms-aomxComp_delete: Calling Cisco OMX Core to free the component 0x706600:0x707fd8 ......
3019 DEB Mar 21 17:15:05.911582 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 26
3020 DEB Mar 21 17:15:05.911620 (10539:10617) voice_feedback-libms: libmsAEventThread - Received Remote Media Removed, sessionid x6
3021 NOT Mar 21 17:15:05.911809 (10502:10502) ms-RTPSESSION.ms_deallocateRTPSessionRx[A:6] exit [status=0]
3022 NOT Mar 21 17:15:05.911849 (10502:10502) ms-SELECTMGR.slm_selectFdRemove Removed slot [idx=1][reserved=0][count=0]
3023 NOT Mar 21 17:15:05.911944 (10502:10502) ms-STUNMGR.stunm_identifyAndRemoveKA[U:0] invalid [handle=-1]
3024 NOT Mar 21 17:15:05.911975 (10502:10502) ms-STUNMGR.stunm_identifyAndRemoveKA[U:0] invalid [handle=-1]
3025 NOT Mar 21 17:15:05.912294 (10502:10502) ms-SESSIONMGR.ms_closeSession[A:6] exit [status=0][errno=77]
3026 NOT Mar 21 17:15:05.912462 (10502:10502) ms-MSAPI.ms_releaseRxPort : port 16386
3027 WRN Mar 21 17:15:05.912525 (10519:10623) voice-[WLAN_API-wlanApiConnectStatusGet] Error getting status
3028 NOT Mar 21 17:15:05.912581 (10502:10502) ms-MSAPI.ms_releaseRxPort : port 16386
3029 NOT Mar 21 17:15:05.912596 (10502:10502) ms-PORTMGR.portm_releaseRxPort  invalid inuse avail [port=16386][availType=1][count=0]
3030 NOT Mar 21 17:15:05.912637 (10519:10623) voice-CC_eventProc(event=129(CC_EV_SIG_CALL_PROGRESS_CODE), lid=0, par=4, par2=0x26)
3031 NOT Mar 21 17:15:05.920755 (10519:10623) voice-CC_eventProc(event=47(CC_EV_SIG_CALL_FAILED), lid=0, par=4, par2=0x26)
3032 NOT Mar 21 17:15:05.920908 (10519:10623) voice-CC_checkLocalConference check conf status on line 0 for call 0
3033 NOT Mar 21 17:15:05.920968 (10519:10623) voice-CC:Failed w/ Calling(38)
3034 NOT Mar 21 17:15:05.921017 (10519:10623) voice-ShmB_AllocItem(): ++++shmb_allocItem count = 3
3035 NOT Mar 21 17:15:05.921153 (10519:10623) voice-LOG_NEW_CALL_STATE {"ref_id": "0000", "old_state": "CC_CST_CALLING", "new_state": "CC_CST_INVALID"}  
3036 NOT Mar 21 17:15:05.921463 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_EMERGENCY argc=2
3037 NOT Mar 21 17:15:05.924395 (10519:10623) voice-VDBUS.CallControlIPC_SignalCallStateChanged Signal call state changed for line (0) call (0) and new state is (11)
3038 NOT Mar 21 17:15:05.931203 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_CALL_INVALID argc=22
3039 NOT Mar 21 17:15:05.931552 (10528:10528) mphone-[CCallAppn_gotoCall]: pCall1->callState=4  pCall1->castState=0
3040 NOT Mar 21 17:15:05.934980 (10519:10623) voice-aud_wb.c::AUDWB_startCpTone lid 0 current tone 0 (SYS_TONE_NONE) request 8 (SYS_TONE_REORDER)
3041 NOT Mar 21 17:15:05.935670 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallHistoryAdd?ext=1&name=myHandy&number=123456789&type=2&starttime=1711041305&endtime=0&currentline=ciscoBuro>
3042 NOT Mar 21 17:15:05.935892 (10502:10502) ms-TONESESSION.ms_startToneDescription [session=2][local=1]
3043 NOT Mar 21 17:15:05.935936 (10502:10502) ms-TONESESSION.saveToneDescription save description to slot 0; new toneIndex is 36
3044 NOT Mar 21 17:15:05.941190 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession>
3045 NOT Mar 21 17:15:05.952592 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_TRANSFER argc=2
3046 NOT Mar 21 17:15:05.973864 (10502:10502) ms-AOMXCLIENTAPI.omx_startSinkPlayout omx_startSinkPlayout
3047 NOT Mar 21 17:15:05.973951 (10502:10502) ms-CALLBKMGR.cb_mediaLocalAdded [callId=0][seh=2]
3048 NOT Mar 21 17:15:05.974023 (10502:10502) ms-TONECORE.tonecorePlayATone_ TONE_START: [toneIndex=36][local=1][network=0][session=2]
3049 NOT Mar 21 17:15:05.974059 (10502:10502) ms-CALLBKMGR.cb_toneInfo Tone Event Update
3050 NOT Mar 21 17:15:05.974102 (10502:10502) ms-SESSIONMGR.ms_startSession[T:2] enter
3051 NOT Mar 21 17:15:05.974131 (10502:10502) ms-SESSIONMGR.ms_startSession[T:2] exit [status=0][errno=0]
3052 DEB Mar 21 17:15:05.974381 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 27
3053 DEB Mar 21 17:15:05.974422 (10539:10617) voice_feedback-libms: libmsAEventThread - Received Local Media Added, sessionid x2
3054 DEB Mar 21 17:15:05.974467 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 41
3055 DEB Mar 21 17:15:05.974483 (10539:10617) voice_feedback-libms: libmsAEventThread - Received TONE Info, tag x0, toneId 7, status 1
3056 NOT Mar 21 17:15:05.983124 (10519:10623) voice-SIP_sessTsEventProc reset tsInviteClient!
3057 NOT Mar 21 17:15:05.983276 (10519:10623) voice-Sess Terminated
3058 NOT Mar 21 17:15:06.812435 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_OFFHOOK>
3059 NOT Mar 21 17:15:06.812580 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SEND_KEY argc=3
3060 WRN Mar 21 17:15:06.821198 (10502:10502) ms-APATH.ms_setHandsetStatus  Not Supported
3061 NOT Mar 21 17:15:06.821286 (10519:10623) voice-### switch on audio dev 1, pap=3
3062 NOT Mar 21 17:15:06.821516 (10502:10502) ms-APATH.ms_setMute [setting mute on]
3063 NOT Mar 21 17:15:06.821549 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
3064 NOT Mar 21 17:15:06.821567 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
3065 NOT Mar 21 17:15:06.821637 (10502:10502) ms-APATH.ms_setInputAudioPath Saving [input audio device = 256]
3066 NOT Mar 21 17:15:06.821656 (10502:10502) ms-APATH.ms_setInputAudioPath No active streams, exiting
3067 NOT Mar 21 17:15:06.821706 (10502:10502) ms-APATH.ms_setOutputAudioPath Saving [output audio device = 0][volume=9]
3068 NOT Mar 21 17:15:06.822027 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_SYSTEM_AUDIO_PATH argc=3
3069 NOT Mar 21 17:15:06.830277 (10502:10502) ms-APATH.ms_setSideTone enable side tone
3070 NOT Mar 21 17:15:06.830453 (10502:10502) ms-APATH.ms_setMute [setting mute off]
3071 NOT Mar 21 17:15:06.830478 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
3072 NOT Mar 21 17:15:06.830492 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
3073 NOT Mar 21 17:15:06.830552 (10519:10623) voice-aud_wb.c::AUDWB_startCpTone lid 0 current tone 8 (SYS_TONE_REORDER) request 8 (SYS_TONE_REORDER)
3074 NOT Mar 21 17:15:06.830581 (10519:10623) voice-aud_wb.c::AUDWB_startCpTone requested tone 8 (SYS_TONE_REORDER) already playing
3075 NOT Mar 21 17:15:07.682412 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_Key_ONHOOK>
3076 NOT Mar 21 17:15:07.682644 (10528:10528) mphone-CCallProxy_sendRequest(): [PXY]send QPE_GUI_MSG_SEND_KEY argc=3
3077 WRN Mar 21 17:15:07.691189 (10502:10502) ms-APATH.ms_setHandsetStatus  Not Supported
3078 WRN Mar 21 17:15:07.691345 (10519:10623) voice-[WLAN_API-wlanApiConnectStatusGet] Error getting status
3079 NOT Mar 21 17:15:07.691381 (10519:10623) voice-CC_eventProc(event=1(CC_EV_USR_ONHOOK), lid=0, par=0, par2=(nil))
3080 NOT Mar 21 17:15:07.691419 (10519:10623) voice-aud_wb.c::AUDWB_startCpTone lid 0 current tone 8 (SYS_TONE_REORDER) request 8 (SYS_TONE_REORDER)
3081 NOT Mar 21 17:15:07.691437 (10519:10623) voice-aud_wb.c::AUDWB_startCpTone requested tone 8 (SYS_TONE_REORDER) already playing
3082 NOT Mar 21 17:15:07.699614 (10519:10623) voice-aud_wb.c::AUDWB_stopCpTone lid 0 current tone 8 (SYS_TONE_REORDER) preempted 0 (SYS_TONE_NONE)
3083 NOT Mar 21 17:15:07.699802 (10502:10502) ms-SESSIONMGR.ms_stopSession[T:2] enter
3084 NOT Mar 21 17:15:07.699853 (10502:10502) ms-TONECORE.tonecoreStopATone_ TONE_STOP: [toneIndex=36][session=2]
3085 NOT Mar 21 17:15:07.699869 (10502:10502) ms-SESSIONMGR.ms_stopSession[T:2] exit [status=0][errno=0]
3086 NOT Mar 21 17:15:07.699990 (10519:10623) voice-aud_wb.c::AUDWB_stopCpTone done, lid 0 current tone 0 (SYS_TONE_NONE) preempted 0 (SYS_TONE_NONE)
3087 NOT Mar 21 17:15:07.700278 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_SYSTEM_AUDIO_PATH argc=3
3088 NOT Mar 21 17:15:07.700582 (10502:10502) ms-APATH.ms_setMute [setting mute on]
3089 NOT Mar 21 17:15:07.700624 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
3090 NOT Mar 21 17:15:07.700640 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
3091 NOT Mar 21 17:15:07.700781 (10502:10502) ms-APATH.ms_setMute [setting mute off]
3092 NOT Mar 21 17:15:07.700801 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
3093 NOT Mar 21 17:15:07.700814 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
3094 NOT Mar 21 17:15:07.700898 (10519:10623) voice-PHN_SET_AUDIO_PATH: Audio Path set to None, clearing MS SW mute
3095 NOT Mar 21 17:15:07.701000 (10519:10623) voice-LOG_NEW_CALL_STATE {"ref_id": "0000", "old_state": "CC_CST_INVALID", "new_state": "CC_CST_IDLE"}  
3096 NOT Mar 21 17:15:07.701271 (10519:10623) voice-VDBUS.CallControlIPC_SignalCallStateChanged Signal call state changed for line (0) call (0) and new state is (0)
3097 NOT Mar 21 17:15:07.701402 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_EMERGENCY argc=2
3098 NOT Mar 21 17:15:07.711200 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_CALL_IDLE argc=22
3099 NOT Mar 21 17:15:07.717191 (10519:10623) voice-[CCTRL]Free Call Obj 0xb593d638(0:0) 
3100 NOT Mar 21 17:15:07.717561 (10502:10502) ms-APATH.ms_setMute [setting mute on]
3101 NOT Mar 21 17:15:07.717612 (10502:10502) ms-aomxSource_setMute: successfully set mute[1].
3102 NOT Mar 21 17:15:07.717628 (10502:10502) ms-DSPMGR.dm_mute Successfully to mute
3103 NOT Mar 21 17:15:07.717808 (10502:10502) ms-APATH.ms_setMute [setting mute off]
3104 NOT Mar 21 17:15:07.717829 (10502:10502) ms-aomxSource_setMute: successfully set mute[0].
3105 NOT Mar 21 17:15:07.717842 (10502:10502) ms-DSPMGR.dm_mute Successfully to unmute
3106 NOT Mar 21 17:15:07.717933 (10519:10623) voice-PHN_SET_AUDIO_PATH: Audio Path set to None, clearing MS SW mute
3107 NOT Mar 21 17:15:07.727802 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession_Clear>
3108 NOT Mar 21 17:15:07.729791 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_CallSession_Clear>
3109 NOT Mar 21 17:15:07.729983 (10528:10528) mphone-CScreen_dispatch(): call agent process <Application_HomeCheck>
3110 NOT Mar 21 17:15:07.741016 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_CALL_EVENT_TRANSFER argc=2
3111 NOT Mar 21 17:15:07.744795 (10528:10528) mphone-CCallProxy_service(): [PXY]svcrecv QPE_VOIP_MSG_SYSTEM_AUDIO_PATH argc=3
3112 NOT Mar 21 17:15:07.756358 (10502:10502) ms-TONECORE.tonecoreToneDone TONE_DONE: CP [toneIndex=36][toneOnId=2][session=2]
3113 NOT Mar 21 17:15:07.756417 (10502:10502) ms-DSPMGR.dm_stopToneLocally omx_stopSinkPlayout on [component=x401]
3114 NOT Mar 21 17:15:07.756434 (10502:10502) ms-aomxSink_stopPort: enter (for port 2)......
3115 NOT Mar 21 17:15:07.797783 (10502:10502) ms-aomxSink_stopPort: tsem_down_timeout now...
3116 NOT Mar 21 17:15:07.797841 (10502:10502) ms-aomxSink_stopPort: ......exit
3117 NOT Mar 21 17:15:07.797856 (10502:10502) ms-AOMXCLIENTAPI.omx_stopSinkPlayout stopSinkPlayout [compType=4]
3118 NOT Mar 21 17:15:07.797872 (10502:10502) ms-DSPMGR.dm_stopToneLocally omx_disconnectComponent on [component=x401]
3119 NOT Mar 21 17:15:07.797888 (10502:10502) ms-DSPMGR.dm_stopToneLocally omx_disconnectComponent on [component=x401]
3120 NOT Mar 21 17:15:07.798344 (10502:10502) ms-AlsaDevice.alsa_close enter
3121 NOT Mar 21 17:15:07.799241 (10502:10502) ms-AlsaDevice.alsa_close set Playback as DEVICE_PCM_CLOSED
3122 NOT Mar 21 17:15:07.799283 (10502:10502) ms-CALLBKMGR.cb_mediaLocalRemoved [callId=0][seh=2]
3123 NOT Mar 21 17:15:07.799349 (10502:10502) ms-CALLBKMGR.cb_toneInfo Tone Event Update
3124 DEB Mar 21 17:15:07.799470 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 28
3125 DEB Mar 21 17:15:07.799504 (10539:10617) voice_feedback-libms: libmsAEventThread - Received Local Media Removed, sessionid x2
3126 DEB Mar 21 17:15:07.799551 (10539:10617) voice_feedback-libms: libmsAEventThread - AEvent- recv, fid = 16, len = 8132, callback type = 41
3127 DEB Mar 21 17:15:07.799567 (10539:10617) voice_feedback-libms: libmsAEventThread - Received TONE Info, tag x0, toneId 7, status 0
3128 NOT Mar 21 17:15:08.601422 (10519:10623) voice-SIP-TRANSAC.updateRegTpAddr [Ext:1] LastIp:192.168.178.45 uiCurIp:192.168.178.45
3129 NOT Mar 21 17:15:08.601673 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  REGISTER sip:192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-aefec949^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=170b0c886e3e5398o0^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: c3c273ab-5b8bdb2e@192.168.178.45^M
	CSeq: 30244 REGISTER^M
	Max-Forwards: 70^M
	Authorization: Digest username="ciscoBuro",realm="fritz.box",nonce="E08706D21387628F",uri="sip:192.168.178.1",algorithm=MD5,response="81e8a35e94a262e0b81871bb6c51baba"^M  
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>;expires=3600^M  
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE^M
	Supported: replaces, sec-agree^M
	^M
3130 NOT Mar 21 17:15:08.601699 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=738 
3131 NOT Mar 21 17:15:08.604126 (10519:10701) voice-[SIP_resolveHostName] host=192.168.178.1 port=0
3132 NOT Mar 21 17:15:08.661170 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 401 Unauthorized^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-aefec949^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=170b0c886e3e5398o0^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=83413836A66EFB95^M  
	Call-ID: c3c273ab-5b8bdb2e@192.168.178.45^M
	CSeq: 30244 REGISTER^M
	WWW-Authenticate: Digest realm="fritz.box", nonce="D6280AFFD607D5C2"^M  
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
3133 NOT Mar 21 17:15:08.661219 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=420 
3134 NOT Mar 21 17:15:08.671428 (10519:10623) voice-SIP-TRANSAC.updateRegTpAddr [Ext:1] LastIp:192.168.178.45 uiCurIp:192.168.178.45
3135 NOT Mar 21 17:15:08.671663 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  REGISTER sip:192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5ca997b2^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=170b0c886e3e5398o0^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: c3c273ab-5b8bdb2e@192.168.178.45^M
	CSeq: 30245 REGISTER^M
	Max-Forwards: 70^M
	Authorization: Digest username="ciscoBuro",realm="fritz.box",nonce="D6280AFFD607D5C2",uri="sip:192.168.178.1",algorithm=MD5,response="9a97b6b9e5de509cac51548eb89e2cae"^M  
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>;expires=3600^M  
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE^M
	Supported: replaces, sec-agree^M
	^M
3136 NOT Mar 21 17:15:08.671689 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=738 
3137 NOT Mar 21 17:15:08.671779 (10519:10701) voice-[SIP_resolveHostName] host=192.168.178.1 port=0
3138 NOT Mar 21 17:15:08.701147 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 200 OK^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-5ca997b2^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=170b0c886e3e5398o0^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=6B899FBD178E6631^M  
	Call-ID: c3c273ab-5b8bdb2e@192.168.178.45^M
	CSeq: 30245 REGISTER^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>;expires=300^M  
	User-Agent: AVM FRITZ!Box 7490 113.07.57 (Sep  1 2023)^M
	Supported: 100rel,replaces,timer^M
	Allow-Events: telephone-event,refer,reg^M
	Allow: INVITE,ACK,OPTIONS,CANCEL,BYE,UPDATE,PRACK,INFO,SUBSCRIBE,NOTIFY,REFER,MESSAGE,PUBLISH^M
	Accept: application/sdp, multipart/mixed^M
	Accept-Encoding: identity^M
	Content-Length: 0^M
	^M
3139 NOT Mar 21 17:15:08.701196 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=704 
3140 NOT Mar 21 17:15:08.701512 (10519:10623) voice-[0]RegOK. NextReg in 299 (1)
3141 NOT Mar 21 17:15:08.701545 (10519:10623) voice-CC_eventProc(event=63(CC_EV_SIG_REGISTER_OK), lid=0, par=0, par2=(nil))
3142 NOT Mar 21 17:15:08.709042 (10519:10623) voice-SIP_subscribeNextMWI: count=0, nItem=0
3143 NOT Mar 21 17:15:12.610029 (10528:10528) mphone-CSSaver_timerCallback: Sending Ping
3144 NOT Mar 21 17:15:12.610085 (10528:10528) mphone-CProto_ping(): ping Request
3145 NOT Mar 21 17:15:12.610231 (10528:10528) mphone-CProto_sendPDU(): sendPDU: header: Fd=18 id=<2058> code=<101>, nLen=20, expectedlen=20
3146 NOT Mar 21 17:15:12.652423 (10519:10519) voice-CRemoteRepoProto_serviceApp(): request code = 101
3147 NOT Mar 21 17:15:12.652479 (10519:10519) voice-CProto_serviceAppInt(): CProto_serviceAppInt request Code 101
3148 NOT Mar 21 17:15:12.652495 (10519:10519) voice-CProto_pingResponse(): send back ping response
3149 NOT Mar 21 17:15:12.652564 (10519:10519) voice-CProto_sendPDU(): sendPDU: header: Fd=36 id=<2058> code=<102>, nLen=20, expectedlen=20
3150 NOT Mar 21 17:15:14.331694 (10519:10623) voice- =====> Send (TCP) [192.168.178.1]:5060 SIP MSG::  SUBSCRIBE sip:ciscoBuro@192.168.178.1 SIP/2.0^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-59dd26c1^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>^M  
	Call-ID: 7e80f04d-23b6786e@192.168.178.45^M
	CSeq: 6995 SUBSCRIBE^M
	Max-Forwards: 70^M
	Contact: "BureauSubscriper" <sip:ciscoBuro@192.168.178.45:5073;transport=tcp>^M  
	Expires: 3600^M
	Event: call-info^M
	User-Agent: Cisco-CP-8841-3PCC/11.3.3^M
	Content-Length: 0^M
	^M
3151 NOT Mar 21 17:15:14.331752 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=495 
3152 NOT Mar 21 17:15:14.351131 (10519:10623) voice- <===== Recv (TCP) [192.168.178.1]:5060 SIP MSG::  SIP/2.0 489 Bad Event^M
	Via: SIP/2.0/TCP 192.168.178.45:5073;branch=z9hG4bK-59dd26c1^M
	From: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=80cbaa0187c92c8d^M  
	To: "BureauSubscriper" <sip:ciscoBuro@192.168.178.1>;tag=1A2500A10F5EEECA^M  
	Call-ID: 7e80f04d-23b6786e@192.168.178.45^M
	CSeq: 6995 SUBSCRIBE^M
	User-Agent: FRITZ!OS^M
	Content-Length: 0^M
	^M
3153 NOT Mar 21 17:15:14.351174 (10519:10623) voice-::End-Of-Sip-Message::	 SIP MSG len=345 
3154 NOT Mar 21 17:15:14.351404 (10519:10623) voice-#### sdlg ts event 28
3155 NOT Mar 21 17:15:14.351422 (10519:10623) voice-SUBS Failed 18 28 39
3156 NOT Mar 21 17:15:14.351451 (10519:10623) voice-#### sdlg ts event 32



2690 ERR Mar 21 17:14:09.258493 (10519:10634) voice-http:pkt_filename: failed to opendir:/var/tmp/packetcapture

Please also mark the comments that contributed to the solution of the article

Content-Key: 8851339932

Url: https://administrator.de/contentid/8851339932

Printed on: April 27, 2024 at 08:04 o'clock

6 Comments

Latest comment

Irgendwann (wohl nach einem Update der Fritzbox) ging es nicht mehr.

AVM hat eine Firmware Änderung gemacht das die Fritte von lokalen VoIP Geräten SIP nur noch mit einer TCP Encapsulation supportet und auch die User/Passwort Credentials müssen eine Mindestanzahl an Stellen haben (8). Siehe den entsprechenden Hinweis dazu im Tutorial!
Vermutlich wirst du da aber eher in die "Caller Blocking" Falle getappt sein die ja auch genau deinem Fehlerbild entspricht! (<callerIdBlocking>2</callerIdBlocking> Parameter)
Sehr sinnvoll ist auch immer ein Blick in die erweiterten FritzBox Logs mit http://fritz.box/support.lua und dort einfach nach "SIP/ "suchen.
Dort befindet sich auch eine Packet Capture Option mit der man ggf. eingehende SIP Pakete der lokalen VoIP Telefone mitschneiden kann.

Passe also deine SEP....xml Konfigurations Datei dementsprechend an und reboote das Telefon noch einmal um die neu einzulesen. Das sollte dann sofort alle deine Probleme lösen!

Gut, da hab ich ja was woran ich mich abarbeiten kann.

Schon mal Danke für deine Antwort.

mfG

Wollte noch die Logs nach reichen - aber der Link geht nicht mehr und ich finde da auch gerade nichts in google.

Aber egal, schaue erstmal nach deinen genannten Punkten.

aber der Link geht nicht mehr

Welchen meinst du?? 🤔

http://fritz.box/support/lua

diesen

Sorry, war ein Tippfehler! 🙈 Richtig ist natürlich http://fritz.box/support.lua Siehe auch hier.

German Question Voice over IP