cancel
Showing results forĀ 
Search instead forĀ 
Did you mean:Ā 
cancel
4387
Views
20
Helpful
22
Replies

SPA504G/freePBX Mark Answered Elsewhere stopped working

hariskar
Level 1
Level 1

I had configured my phone as sip extension in freePBX, but now because sip became legacy I configured it as pjsip. Since then Mark Answered Elsewhere does not work. No other problems.

Are there any settings in webui of my phone to check or change to make it work?

Thank you!

22 Replies 22

Well, the feature has been implemented in 7.4.7. According Release Notes:

Firmware version 7.4.7 includes support for the SIP ā€œReasonā€ header in CANCEL
messages. No configuration is required.

It just should work with no specific configuration required.

I remember someone's issue affecting line in "private" mode, but I may not remember it correctly or it has been bug affecting particular firmware version only.

 

You are claiming it's working with chan_sip but it doesn't work with chan_pjsip.

 

Can you catch INVITE/CANCEL pair of packets for (working) chan_sip configuration as well as for (non working) chan_pjsip ? May be the comparsion of packets will enlight us.

 

May be two "Reason:" lines or it's order are causing it (I'm just blindly guessing) ...

I have read that SIP CANCEL packet has only one line in chan_sip extensions. I will try to reproduce it. 

 

In my case, since SIP CANCEL lines are OK and arrive to my phone, this means that eather a setting is wrong, either there is a bug on the cisco side (my phones are spa504g and spa303 and both show answered calls as missed). I believe it is a wrong or incompatible setting of me. But what?? Maybe some SIP Parameters (although most/all of them are default)?

As I said with the same phone configuration and phones configured as chan_pjsip on freepbx, there were no problems.

Thank you!

Definitely firmware bug. I assume code function like "fetch Reason header line" which returns single line only - and it's not the one recognized as "Answered Elsewhere" event. While you may report it to Cisco, your chances it will be patched in reasonable time are very low.

It seems you are not only user affected. Read suppress_q850_reason_headers 

Hope it will help.

Thank you for reply, now I know it is a bug I can stop looking at all Freepbx and Cisco configuration options to find what is wrong.

I configured my extensions as chan_sip again and Mark Answered Elswhere works fine again!

 

I did not find suppress_q850_reason_headers  option in Freepbx settings.

 

Here is the Wireshark capture:

 

177277	1119.732103	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 31\n
177280	1120.221228	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
177281	1120.221576	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
177313	1124.731293	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
177314	1124.731353	192.168.1.2	192.168.1.10	ICMP	128	Destination unreachable (Port unreachable)
177315	1124.731805	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
177316	1124.732349	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
177317	1124.732708	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
177318	1124.733137	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949de9e0 949dce30 bcts:1 nRef:0\n
177319	1124.733470	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949de9e0 949dce30 bcts:1 nRef:0\n
179995	1134.731192	192.168.1.10	192.168.1.2	Syslog	78	LOCAL3.DEBUG: regTimerCallBack 51 bTryAltReg\n
179996	1134.731251	192.168.1.2	192.168.1.10	ICMP	106	Destination unreachable (Port unreachable)
179997	1134.731987	192.168.1.10	192.168.1.2	Syslog	75	LOCAL3.DEBUG: #### create TS 1 0x949ddfe0\n
179998	1134.733597	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
179999	1134.734113	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
180000	1134.734897	192.168.1.10	192.168.1.2	Syslog	400	NOTIFY sip:192.168.1.5:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-55bb83bf\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 87 NOTIFY\r\nMax-Forwards: 70\r\nContact: <sip:31@192.168.1.10:5160>\r\nEvent: keep-alive\r\nUser-Agent: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
180001	1134.735301	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
180002	1134.735685	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
180003	1134.737655	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
180004	1134.740476	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
180005	1134.740476	192.168.1.10	192.168.1.2	Syslog	473	SIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-55bb83bf;received=192.168.1.10;rport=5160\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>;tag=as59710092\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 87 NOTIFY\r\nServer: FPBX-15.0.16.81(13.36.0)\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE\r\nSupported: replaces, timer\r\nContent-Length: 0\r\n\r\n
180006	1134.740476	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
180007	1134.740476	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
180008	1134.740476	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 27 state:1 \n
180009	1134.740476	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 27 state:1 \n
180010	1134.740476	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 27\n
180011	1134.741246	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
180012	1134.741596	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
180013	1134.742055	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 31\n
180015	1135.231121	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
180016	1135.231474	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
184588	1139.741180	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
184589	1139.741199	192.168.1.2	192.168.1.10	ICMP	128	Destination unreachable (Port unreachable)
184590	1139.741846	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
184591	1139.742235	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
184592	1139.742603	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
184593	1139.743031	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949dce10 949dea00 bcts:1 nRef:0\n
184594	1139.743360	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949dce10 949dea00 bcts:1 nRef:0\n
184628	1141.643031	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
184629	1141.643090	192.168.1.2	192.168.1.10	ICMP	102	Destination unreachable (Port unreachable)
184630	1141.643569	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
184631	1141.644196	192.168.1.10	192.168.1.2	Syslog	591	OPTIONS sip:31@192.168.1.10:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK08888625\r\nMax-Forwards: 70\r\nFrom: "Unknown" <sip:Unknown@192.168.1.5:5160>;tag=as7e0be3b0\r\nTo: <sip:31@192.168.1.10:5160>\r\nContact: <sip:Unknown@192.168.1.5:5160>\r\nCall-ID: 5e1d2dea5f1bbdc76c79a8d11e4670ac@192.168.1.5:5160\r\nCSeq: 102 OPTIONS\r\nUser-Agent: FPBX-15.0.16.81(13.36.0)\r\nDate: Tue, 01 Dec 2020 09:36:27 GMT\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE\r\nSupported: replaces, timer\r\nContent-Length: 0\r\n\r\n
184632	1141.644819	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184633	1141.645686	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184634	1141.647519	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{414)\n
184635	1141.647847	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{414)\n
184636	1141.648267	192.168.1.10	192.168.1.2	Syslog	456	SIP/2.0 200 OK\r\nTo: <sip:31@192.168.1.10:5160>;tag=15989d4f18f128cci0\r\nFrom: "Unknown" <sip:Unknown@192.168.1.5:5160>;tag=as7e0be3b0\r\nCall-ID: 5e1d2dea5f1bbdc76c79a8d11e4670ac@192.168.1.5:5160\r\nCSeq: 102 OPTIONS\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK08888625\r\nServer: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\nAllow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE\r\nSupported: replaces\r\n\r\n
184637	1141.648750	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184638	1141.649195	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184790	1149.741125	192.168.1.10	192.168.1.2	Syslog	78	LOCAL3.DEBUG: regTimerCallBack 51 bTryAltReg\n
184791	1149.741148	192.168.1.2	192.168.1.10	ICMP	106	Destination unreachable (Port unreachable)
184792	1149.741927	192.168.1.10	192.168.1.2	Syslog	75	LOCAL3.DEBUG: #### create TS 1 0x949ddf00\n
184793	1149.743490	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
184794	1149.744046	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
184795	1149.744836	192.168.1.10	192.168.1.2	Syslog	400	NOTIFY sip:192.168.1.5:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-cccff91f\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 88 NOTIFY\r\nMax-Forwards: 70\r\nContact: <sip:31@192.168.1.10:5160>\r\nEvent: keep-alive\r\nUser-Agent: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
184796	1149.745253	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184797	1149.745625	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184798	1149.747600	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
184799	1149.747986	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
184800	1149.748430	192.168.1.10	192.168.1.2	Syslog	473	SIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-cccff91f;received=192.168.1.10;rport=5160\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>;tag=as59710092\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 88 NOTIFY\r\nServer: FPBX-15.0.16.81(13.36.0)\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE\r\nSupported: replaces, timer\r\nContent-Length: 0\r\n\r\n
184801	1149.748828	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184802	1149.749203	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
184803	1149.749725	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 27 state:1 \n
184804	1149.750122	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 27 state:1 \n
184805	1149.750803	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 27\n
184806	1149.751843	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
184807	1149.751843	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
184808	1149.752110	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 31\n
184809	1150.241265	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 3 state:3 \n
184810	1150.241604	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 3 state:3 \n
184994	1154.757705	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 9 state:3 \n
184995	1154.757765	192.168.1.2	192.168.1.10	ICMP	128	Destination unreachable (Port unreachable)
184996	1154.758228	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddf00 event 9 state:3 \n
184997	1154.758788	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
184998	1154.759117	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
184999	1154.759544	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddf00 clean 0 949de9e0 949dd4c0 bcts:1 nRef:0\n
185000	1154.759876	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddf00 clean 0 949de9e0 949dd4c0 bcts:1 nRef:0\n
185016	1157.651120	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ca300 clean 0 949de2f0 949dce10 bcts:0 nRef:0\n
185017	1157.651176	192.168.1.2	192.168.1.10	ICMP	133	Destination unreachable (Port unreachable)
185018	1157.651635	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ca300 clean 0 949de2f0 949dce10 bcts:0 nRef:0\n
185070	1164.757672	192.168.1.10	192.168.1.2	Syslog	78	LOCAL3.DEBUG: regTimerCallBack 51 bTryAltReg\n
185071	1164.757730	192.168.1.2	192.168.1.10	ICMP	106	Destination unreachable (Port unreachable)
185072	1164.758513	192.168.1.10	192.168.1.2	Syslog	75	LOCAL3.DEBUG: #### create TS 1 0x949ddfe0\n
185073	1164.760084	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
185074	1164.760719	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{358)\n
185075	1164.761654	192.168.1.10	192.168.1.2	Syslog	400	NOTIFY sip:192.168.1.5:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-6d97091d\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 89 NOTIFY\r\nMax-Forwards: 70\r\nContact: <sip:31@192.168.1.10:5160>\r\nEvent: keep-alive\r\nUser-Agent: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
185076	1164.762060	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185077	1164.762457	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185078	1164.764643	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185079	1164.765025	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185080	1164.765465	192.168.1.10	192.168.1.2	Syslog	473	SIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 192.168.1.10:5160;branch=z9hG4bK-6d97091d;received=192.168.1.10;rport=5160\r\nFrom: <sip:31@192.168.1.5>;tag=a3c34b73f637b0f8o0\r\nTo: <sip:192.168.1.5>;tag=as59710092\r\nCall-ID: b4410bef-d0f28aac@192.168.1.10\r\nCSeq: 89 NOTIFY\r\nServer: FPBX-15.0.16.81(13.36.0)\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE\r\nSupported: replaces, timer\r\nContent-Length: 0\r\n\r\n
185081	1164.765845	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185082	1164.766222	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185083	1164.766717	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 27 state:1 \n
185084	1164.767061	192.168.1.10	192.168.1.2	Syslog	101	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 27 state:1 \n
185085	1164.767450	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 27\n
185086	1164.767862	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
185087	1164.768209	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: start TMR K\n
185088	1164.768680	192.168.1.10	192.168.1.2	Syslog	69	LOCAL3.DEBUG: SIP_regTsEventProc 31\n
185089	1165.257607	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
185090	1165.257972	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 3 state:3 \n
185095	1166.323094	192.168.1.10	192.168.1.2	Syslog	70	LOCAL2.DEBUG: SDP RTPMAP 111 --> 130\n
185096	1166.323153	192.168.1.2	192.168.1.10	ICMP	98	Destination unreachable (Port unreachable)
185097	1166.323925	192.168.1.10	192.168.1.2	Syslog	70	LOCAL2.DEBUG: SDP RTPMAP 101 --> 142\n
185098	1166.324733	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185099	1166.325077	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185100	1166.325657	192.168.1.10	192.168.1.2	Syslog	1047	INVITE sip:31@192.168.1.10:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nMax-Forwards: 70\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nTo: <sip:31@192.168.1.10:5160>\r\nContact: <sip:6906753619@192.168.1.5:5160>\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 INVITE\r\nUser-Agent: FPBX-15.0.16.81(13.36.0)\r\nDate: Tue, 01 Dec 2020 09:36:52 GMT\r\nAllow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE\r\nSupported: replaces, timer\r\nP-Asserted-Identity: "6906753619" <sip:6906753619@192.168.1.5>\r\nContent-Type: application/sdp\r\nContent-Length: 352\r\n\r\nv=0\r\no=root 1955507197 1955507197 IN IP4 192.168.1.5\r\ns=Asterisk PBX 13.36.0\r\nc=IN IP4 192.168.1.5\r\nt=0 0\r\nm=audio 11184 RTP/AVP 8 0 3 111 9 101\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:3 GSM/8000\r\na=rtpmap:111 G726-32/8000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-16\r\na=ptime:20\r\na=maxptime:150\r\na=sendrecv\r\n
185101	1166.325974	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185102	1166.326342	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185103	1166.329309	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{308)\n
185104	1166.329309	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{308)\n
185105	1166.329510	192.168.1.10	192.168.1.2	Syslog	350	SIP/2.0 100 Trying\r\nTo: <sip:31@192.168.1.10:5160>\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 INVITE\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nServer: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
185106	1166.329856	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185107	1166.331513	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185108	1166.331513	192.168.1.10	192.168.1.2	Syslog	80	LOCAL3.DEBUG: >>>> SIP: SIP_getRemotePartyId 0\n
185109	1166.331513	192.168.1.10	192.168.1.2	Syslog	102	LOCAL3.DEBUG: ucCallerIdHeader 0 bUseFromOnly 0 bNoFrom 0 bPrivacy 0\n
185110	1166.331979	192.168.1.10	192.168.1.2	Syslog	105	LOCAL3.DEBUG: SIP_getRemotePartyId(), 1 pna->uri.hp.strHose=192.168.1.5\n
185111	1166.332399	192.168.1.10	192.168.1.2	Syslog	94	LOCAL3.DEBUG: SIP_getRemotePartyId(), 1 pna->uri.hp.usPort=0\n
185112	1166.332778	192.168.1.10	192.168.1.2	Syslog	97	LOCAL3.DEBUG: SIP_getRemotePartyId(), 1 lid=0 call_log_domain=0\n
185113	1166.333191	192.168.1.10	192.168.1.2	Syslog	99	LOCAL3.DEBUG: SIP_getRemotePartyId(), 1 cinf->strPhone=6906753619\n
185114	1166.333572	192.168.1.10	192.168.1.2	Syslog	105	LOCAL3.DEBUG: [SIP_getRemotePartyId] name="6906753619" phone=6906753619\n
185115	1166.334030	192.168.1.10	192.168.1.2	Syslog	84	LOCAL3.DEBUG: [SIP_getRemotePartyId] splash attr=0\n
185116	1166.334661	192.168.1.10	192.168.1.2	Syslog	89	LOCAL3.DEBUG: [SIP_getRemotePartyId] try to call id_map\n
185117	1166.335119	192.168.1.10	192.168.1.2	Syslog	99	LOCAL3.DEBUG: SIP_getRemotePartyId(), 4 cinf->strPhone=6906753619\n
185118	1166.335510	192.168.1.10	192.168.1.2	Syslog	97	LOCAL3.DEBUG: SIP_getRemotePartyId(), cinf->strPhone=6906753619\n
185119	1166.335888	192.168.1.10	192.168.1.2	Syslog	96	LOCAL3.DEBUG: SIP_getRemotePartyId(), cinf->strName=6906753619\n
185120	1166.336266	192.168.1.10	192.168.1.2	Syslog	81	LOCAL3.DEBUG: <<<< 2 SIP: SIP_getRemotePartyId \n
185121	1166.336925	192.168.1.10	192.168.1.2	Syslog	92	LOCAL0.INFO: +++++ find idle call i = 0, caid=0 (asked 0)\n
185122	1166.337273	192.168.1.10	192.168.1.2	Syslog	92	LOCAL0.INFO: +++++ find idle call i = 0, caid=0 (asked 0)\n
185123	1166.337836	192.168.1.10	192.168.1.2	Syslog	71	LOCAL0.INFO: [0]CC:NewCallState 10/0\n
185124	1166.338163	192.168.1.10	192.168.1.2	Syslog	71	LOCAL0.INFO: [0]CC:NewCallState 10/0\n
185125	1166.338602	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185126	1166.338976	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185127	1166.340135	192.168.1.10	192.168.1.2	Syslog	97	LOCAL3.DEBUG: [CCTRL]record lcr phone=6906753619 exten=0 type=0\n
185128	1166.341027	192.168.1.10	192.168.1.2	Syslog	72	LOCAL0.INFO: START_RING in CC_refresh\n
185129	1166.341374	192.168.1.10	192.168.1.2	Syslog	72	LOCAL0.INFO: START_RING in CC_refresh\n
185130	1166.377937	192.168.1.10	192.168.1.2	Syslog	70	LOCAL2.DEBUG: SDP RTPMAP 111 --> 130\n
185131	1166.378650	192.168.1.10	192.168.1.2	Syslog	70	LOCAL2.DEBUG: SDP RTPMAP 101 --> 142\n
185132	1166.379538	192.168.1.10	192.168.1.2	Syslog	72	LOCAL1.DEBUG: In InitRemoteVoipMetrics\n
185133	1166.384688	192.168.1.10	192.168.1.2	Syslog	66	LOCAL1.DEBUG: In InitVoipMetrics\n
185134	1166.385243	192.168.1.10	192.168.1.2	Syslog	80	LOCAL2.DEBUG: [0:0]AUD ALLOC CALL (port=16390)\n
185135	1166.387422	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{369)\n
185136	1166.387797	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{369)\n
185137	1166.388205	192.168.1.10	192.168.1.2	Syslog	411	SIP/2.0 180 Ringing\r\nTo: <sip:31@192.168.1.10:5160>;tag=1da409d996fea04di0\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 INVITE\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nContact: <sip:31@192.168.1.10:5160>\r\nServer: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
185138	1166.388792	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185139	1166.389171	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185173	1169.771171	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
185174	1169.771230	192.168.1.2	192.168.1.10	ICMP	128	Destination unreachable (Port unreachable)
185175	1169.771672	192.168.1.10	192.168.1.2	Syslog	100	LOCAL0.INFO: SIP_tsClientEventProc ts:0x949ddfe0 event 9 state:3 \n
185176	1169.772205	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
185177	1169.772785	192.168.1.10	192.168.1.2	Syslog	61	LOCAL0.INFO: Timer K Fires\n
185178	1169.773335	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949de9e0 949dce30 bcts:1 nRef:0\n
185179	1169.774053	192.168.1.10	192.168.1.2	Syslog	105	LOCAL0.INFO: +++ ts 0x949ddfe0 clean 0 949de9e0 949dce30 bcts:1 nRef:0\n
185202	1174.622976	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185203	1174.623014	192.168.1.2	192.168.1.10	ICMP	102	Destination unreachable (Port unreachable)
185204	1174.624168	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185205	1174.624813	192.168.1.10	192.168.1.2	Syslog	452	CANCEL sip:31@192.168.1.10:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nMax-Forwards: 70\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nTo: <sip:31@192.168.1.10:5160>\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 CANCEL\r\nUser-Agent: FPBX-15.0.16.81(13.36.0)\r\nReason: SIP;cause=200;text="Call completed elsewhere"\r\nContent-Length: 0\r\n\r\n
185206	1174.625227	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185207	1174.625591	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185208	1174.627955	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{343)\n
185209	1174.628290	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{343)\n
185210	1174.629159	192.168.1.10	192.168.1.2	Syslog	385	SIP/2.0 487 Request Terminated\r\nTo: <sip:31@192.168.1.10:5160>;tag=1da409d996fea04di0\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 INVITE\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nServer: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
185211	1174.629559	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185212	1174.629943	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185214	1174.632678	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{327)\n
185215	1174.633108	192.168.1.10	192.168.1.2	Syslog	79	LOCAL0.INFO: [0:5160]->192.168.1.5:5160{327)\n
185216	1174.633917	192.168.1.10	192.168.1.2	Syslog	369	SIP/2.0 200 OK\r\nTo: <sip:31@192.168.1.10:5160>;tag=1da409d996fea04di0\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 CANCEL\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nServer: Cisco/SPA504G-7.6.2g\r\nContent-Length: 0\r\n\r\n
185217	1174.634524	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185218	1174.634908	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185219	1174.635838	192.168.1.10	192.168.1.2	Syslog	65	LOCAL2.DEBUG: [0:0]AUD Rel Call\n
185220	1174.636308	192.168.1.10	192.168.1.2	Syslog	71	LOCAL0.INFO: [0]CC:NewCallState 0/10\n
185221	1174.636828	192.168.1.10	192.168.1.2	Syslog	71	LOCAL0.INFO: [0]CC:NewCallState 0/10\n
185222	1174.637257	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185223	1174.637676	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185224	1174.648266	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185225	1174.648991	192.168.1.10	192.168.1.2	Syslog	74	LOCAL0.INFO: [0:5160]<<192.168.1.5:5160\n
185226	1174.649432	192.168.1.10	192.168.1.2	Syslog	458	ACK sip:31@192.168.1.10:5160 SIP/2.0\r\nVia: SIP/2.0/UDP 192.168.1.5:5160;branch=z9hG4bK2e4767c0\r\nMax-Forwards: 70\r\nFrom: "6906753619" <sip:6906753619@192.168.1.5:5160>;tag=as2d7cf97b\r\nTo: <sip:31@192.168.1.10:5160>;tag=1da409d996fea04di0\r\nContact: <sip:6906753619@192.168.1.5:5160>\r\nCall-ID: 5ffd13a154ccff383a87218973b14203@192.168.1.5:5160\r\nCSeq: 102 ACK\r\nUser-Agent: FPBX-15.0.16.81(13.36.0)\r\nContent-Length: 0\r\n\r\n
185227	1174.649813	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n
185228	1174.650186	192.168.1.10	192.168.1.2	Syslog	60	LOCAL0.INFO: \n

 

While freePBX is an Asterisk based bundle, it may not use all those options available in Asterisk. Or it may be based on older version of Asterisk with no support for the option. I'm not familiar with freePBX enough to advise. You asked for help with it in freePBX community forum - it's definitely better place to ask this.

Glad to hear you solvedworkarounded the issue.

suppress_q850_reason_headers is not in Freepbx options interface, but I added in an Asterisk config file the following code, made my extensions chan_pjsip again and now Mark Answered Elsewhere works because second reason is suppressed. So if a new firmware is released with a fix I will remove the code above.
Where could I submit this bug?
Thank you for all help!!!

[6104](+type=endpoint)

suppress_q850_reason_headers = yes

Bug in SPA5xx firmware can be reported to Cisco SMB technical support. It's reachable by phone only as far as i know.

Hello hariskar,

 

Typically you have to open a case with STAC so they can validate the issue if it is not a configuration problem. Then they take it from there and proceed with either file or not file a bug. 

 

Since the SPA504G is announced EoS/EoL June 2020, the final software maintenance releases or bug fixes are terminated on June 1, 2020, so after this date Cisco Engineering will no longer repair, maintain, or test product software. (End-of-Sale and End-of-Life Announcement for the Cisco All Remaining Cisco Small Business SPA500 Series IP Phones - Cisco)

 

Regards,

Martin