Extensions reconnecting

Hi, I’m a complete newbie to FreePBX and would like some advice. I inherited a server with WMvare ESXi from a previous administrator and hosted FreePBX on it. I am replacing this server with a new server that hosts Hyper-v. I made a full backup of FreePBX on the old server, installed FreePBX on the new server and then restored from the backup. Now I have the problem that the extensions keeps connecting and disconnecting. The following messages appear in the log:

818 [2024-10-28 09:47:09] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 25 is now Reachable
819 [2024-10-28 09:47:09] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 25/sip:[email protected]:5060 is now Reachable. RTT: 23.878 msec
820 [2024-10-28 09:47:18] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘44’ with expiration of 180 seconds
821 [2024-10-28 09:47:18] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 44 is now Reachable
822 [2024-10-28 09:47:18] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 44/sip:[email protected]:5060 is now Reachable. RTT: 36.519 msec
823 [2024-10-28 09:47:37] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 21 is now Unreachable
824 [2024-10-28 09:47:37] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 21/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
825 [2024-10-28 09:47:57] VERBOSE[2434] res_pjsip_registrar.c: Removed contact ‘sip:[email protected]:5060’ from AOR ‘21’ due to expiration
826 [2024-10-28 09:47:57] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 21/sip:[email protected]:5060 has been deleted
827 [2024-10-28 09:48:13] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 29/sip:[email protected]:5060 has been deleted
828 [2024-10-28 09:48:13] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 29 is now Unreachable
829 [2024-10-28 09:48:32] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘21’ with expiration of 180 seconds
830 [2024-10-28 09:48:34] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 21 is now Reachable
831 [2024-10-28 09:48:34] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 21/sip:[email protected]:5060 is now Reachable. RTT: 1523.535 msec
832 [2024-10-28 09:48:41] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘29’ with expiration of 180 seconds
833 [2024-10-28 09:48:41] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 29 is now Reachable
834 [2024-10-28 09:48:41] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 29/sip:[email protected]:5060 is now Reachable. RTT: 36.286 msec
835 [2024-10-28 09:48:48] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 41/sip:[email protected]:5060 has been deleted
836 [2024-10-28 09:48:48] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 41 is now Unreachable
837 [2024-10-28 09:49:11] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘41’ with expiration of 180 seconds
838 [2024-10-28 09:49:11] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 41 is now Reachable
839 [2024-10-28 09:49:11] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 41/sip:[email protected]:5060 is now Reachable. RTT: 37.317 msec
840 [2024-10-28 09:50:52] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 32 is now Unreachable
841 [2024-10-28 09:50:52] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 32/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
842 [2024-10-28 09:51:00] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 24 is now Unreachable
843 [2024-10-28 09:51:00] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 24/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
844 [2024-10-28 09:51:04] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 25/sip:[email protected]:5060 has been deleted
845 [2024-10-28 09:51:04] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 25 is now Unreachable
846 [2024-10-28 09:51:20] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 14 is now Unreachable
847 [2024-10-28 09:51:20] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 14/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
848 [2024-10-28 09:51:23] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 40 is now Unreachable
849 [2024-10-28 09:51:23] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 40/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
850 [2024-10-28 09:51:49] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 32 is now Reachable
851 [2024-10-28 09:51:49] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 32/sip:[email protected]:5060 is now Reachable. RTT: 22.055 msec
852 [2024-10-28 09:51:57] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 24 is now Reachable
853 [2024-10-28 09:51:57] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 24/sip:[email protected]:5060 is now Reachable. RTT: 21.654 msec
854 [2024-10-28 09:52:18] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 14 is now Reachable
855 [2024-10-28 09:52:18] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 14/sip:[email protected]:5060 is now Reachable. RTT: 1523.350 msec
856 [2024-10-28 09:52:20] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 40 is now Reachable
857 [2024-10-28 09:52:20] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 40/sip:[email protected]:5060 is now Reachable. RTT: 22.039 msec
858 [2024-10-28 09:52:53] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 45 is now Unreachable
859 [2024-10-28 09:52:53] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 45/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
860 [2024-10-28 09:53:02] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 38 is now Unreachable
861 [2024-10-28 09:53:02] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 38/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
862 [2024-10-28 09:53:50] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Unreachable
863 [2024-10-28 09:53:50] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
864 [2024-10-28 09:53:50] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 45 is now Reachable
865 [2024-10-28 09:53:50] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 45/sip:[email protected]:5060 is now Reachable. RTT: 22.293 msec
866 [2024-10-28 09:53:58] VERBOSE[2434] res_pjsip_registrar.c: Removed contact ‘sip:[email protected]:5060’ from AOR ‘13’ due to expiration
867 [2024-10-28 09:53:58] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 13/sip:[email protected]:5060 has been deleted
868 [2024-10-28 09:53:58] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 13 is now Unreachable
869 [2024-10-28 09:54:00] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 38 is now Reachable
870 [2024-10-28 09:54:00] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 38/sip:[email protected]:5060 is now Reachable. RTT: 1523.085 msec
871 [2024-10-28 09:54:00] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 24 is now Unreachable
872 [2024-10-28 09:54:00] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 24/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
873 [2024-10-28 09:54:06] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 39 is now Unreachable
874 [2024-10-28 09:54:06] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 39/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
875 [2024-10-28 09:54:41] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 31/sip:[email protected]:5060 has been deleted
876 [2024-10-28 09:54:41] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 31 is now Unreachable
877 [2024-10-28 09:54:50] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘42’ with expiration of 180 seconds
878 [2024-10-28 09:54:51] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 42 is now Reachable
879 [2024-10-28 09:54:51] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 42/sip:[email protected]:5060 is now Reachable. RTT: 1523.011 msec
880 [2024-10-28 09:54:57] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 24 is now Reachable
881 [2024-10-28 09:54:57] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 24/sip:[email protected]:5060 is now Reachable. RTT: 23.514 msec
882 [2024-10-28 09:55:03] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 39 is now Reachable
883 [2024-10-28 09:55:03] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 39/sip:[email protected]:5060 is now Reachable. RTT: 24.069 msec
884 [2024-10-28 09:55:47] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Reachable
885 [2024-10-28 09:55:47] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 is now Reachable. RTT: 22.350 msec
886 [2024-10-28 09:55:50] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 28 is now Unreachable
887 [2024-10-28 09:55:50] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 28/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
888 [2024-10-28 09:55:53] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 43 is now Unreachable
889 [2024-10-28 09:55:53] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 43/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
890 [2024-10-28 09:56:01] VERBOSE[23274] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘37’ with expiration of 180 seconds
891 [2024-10-28 09:56:01] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 37 is now Reachable
892 [2024-10-28 09:56:01] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 37/sip:[email protected]:5060 is now Reachable. RTT: 36.398 msec
893 [2024-10-28 09:56:47] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 28 is now Reachable
894 [2024-10-28 09:56:47] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 28/sip:[email protected]:5060 is now Reachable. RTT: 23.086 msec
895 [2024-10-28 09:56:50] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 43 is now Reachable
896 [2024-10-28 09:56:50] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 43/sip:[email protected]:5060 is now Reachable. RTT: 23.548 msec
897 [2024-10-28 09:57:21] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 44 is now Unreachable
898 [2024-10-28 09:57:21] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 44/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
899 [2024-10-28 09:57:45] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 20 is now Unreachable
900 [2024-10-28 09:57:45] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 20/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
901 [2024-10-28 09:57:58] VERBOSE[2434] res_pjsip_registrar.c: Removed contact ‘sip:[email protected]:5060’ from AOR ‘33’ due to expiration
902 [2024-10-28 09:57:58] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 33/sip:[email protected]:5060 has been deleted
903 [2024-10-28 09:57:58] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 33 is now Unreachable
904 [2024-10-28 09:58:07] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 has been deleted
905 [2024-10-28 09:58:07] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Unreachable
906 [2024-10-28 09:58:18] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 44 is now Reachable
907 [2024-10-28 09:58:18] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 44/sip:[email protected]:5060 is now Reachable. RTT: 23.390 msec
908 [2024-10-28 09:58:23] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 40 is now Unreachable
909 [2024-10-28 09:58:23] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 40/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
910 [2024-10-28 09:58:34] VERBOSE[35788] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘34’ with expiration of 180 seconds
911 [2024-10-28 09:58:34] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Reachable
912 [2024-10-28 09:58:34] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 is now Reachable. RTT: 35.709 msec
913 [2024-10-28 09:58:42] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 20 is now Reachable
914 [2024-10-28 09:58:42] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 20/sip:[email protected]:5060 is now Reachable. RTT: 23.847 msec
915 [2024-10-28 09:58:50] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 28 is now Unreachable
916 [2024-10-28 09:58:50] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 28/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
917 [2024-10-28 09:58:53] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 43 is now Unreachable
918 [2024-10-28 09:58:53] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 43/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
919 [2024-10-28 09:59:20] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 40 is now Reachable
920 [2024-10-28 09:59:20] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 40/sip:[email protected]:5060 is now Reachable. RTT: 522.987 msec
921 [2024-10-28 09:59:47] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 28 is now Reachable
922 [2024-10-28 09:59:47] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 28/sip:[email protected]:5060 is now Reachable. RTT: 22.060 msec
923 [2024-10-28 09:59:50] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 43 is now Reachable
924 [2024-10-28 09:59:50] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 43/sip:[email protected]:5060 is now Reachable. RTT: 22.929 msec
925 [2024-10-28 09:59:52] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 32 is now Unreachable
926 [2024-10-28 09:59:52] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 32/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
927 [2024-10-28 10:00:51] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 32 is now Reachable
928 [2024-10-28 10:00:51] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 32/sip:[email protected]:5060 is now Reachable. RTT: 1523.526 msec
929 [2024-10-28 10:00:58] VERBOSE[2434] res_pjsip_registrar.c: Removed contact ‘sip:[email protected]:5060’ from AOR ‘38’ due to expiration
930 [2024-10-28 10:00:58] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 38/sip:[email protected]:5060 has been deleted
931 [2024-10-28 10:00:58] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 38 is now Unreachable
932 [2024-10-28 10:01:36] VERBOSE[35788] res_pjsip_registrar.c: Added contact ‘sip:[email protected]:5060’ to AOR ‘23’ with expiration of 180 seconds
933 [2024-10-28 10:01:36] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 23 is now Reachable
934 [2024-10-28 10:01:36] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 23/sip:[email protected]:5060 is now Reachable. RTT: 36.817 msec
935 [2024-10-28 10:02:37] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Unreachable
936 [2024-10-28 10:02:37] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
937 [2024-10-28 10:02:45] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 20 is now Unreachable
938 [2024-10-28 10:02:45] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 20/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec
939 [2024-10-28 10:03:34] VERBOSE[35788] res_pjsip/pjsip_configuration.c: Endpoint 34 is now Reachable
940 [2024-10-28 10:03:34] VERBOSE[35788] res_pjsip/pjsip_options.c: Contact 34/sip:[email protected]:5060 is now Reachable. RTT: 23.301 msec
941 [2024-10-28 10:03:42] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 20 is now Reachable
942 [2024-10-28 10:03:42] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 20/sip:[email protected]:5060 is now Reachable. RTT: 21.347 msec
943 [2024-10-28 10:03:52] VERBOSE[23274] res_pjsip/pjsip_configuration.c: Endpoint 32 is now Unreachable
944 [2024-10-28 10:03:52] VERBOSE[23274] res_pjsip/pjsip_options.c: Contact 32/sip:[email protected]:5060 is now Unreachable. RTT: 0.000 msec

I tried reducing the Qualify frequency from 60 to 15 see Endpoint is now Reachable then Unreachable - #2 by bksales, but that didn’t help.

Original ESXi server: 192.168.1.200
New Hyper-V server: 10.100.100.200
FreePBX: 192.168.5.2
FreePBX and the phones are in the same location on the same network.

Can you advise me what is the problem?
Thanks.

Translated with DeepL.com (free version)

When qualify is enabled, the PBX sends a SIP OPTIONS packet to the registered device, and the device must respond promptly (usually with an OK). If there is no response back to the PBX, then the endpoint is considered unreachable. Intermittent unreachable is usually indicative of a networking issue, something is interrupting the delivery of either the OPTIONS or the OK.

Although it doesn’t affect the basic principles here, the response can be any response, as long as it is a well formed SIP response, that matches the request.

2 Likes

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.