I recently ran several update scripts on my box updating from 6.12.65 to current (10.13.66-11). I have been having intermittent disconnect problems - sometimes a few times a day. The call is disconnected and there is silence on the line but the phone appears to stay connected for a period of time. Every phone in my system does this at the same time. I was in the freepbx interface one of the times, and I got a red error in the top right that said that it could not connect to asterisk. Below is a dump from the full logfile. This appears to be the relevant section.
Any help would be much appreciated.
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Caller ID name is ‘393Pink:TOLL FREE CALL’ number is ‘8007465334’
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Methodology of ring is ‘firstavailable’
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Added extension 201 to extension map
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Added extension 202 to extension map
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Added extension 203 to extension map
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Added extension 204 to extension map
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 201 cf is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 202 cf is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 203 cf is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 204 cf is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 201 do not disturb is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 202 do not disturb is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 203 do not disturb is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 204 do not disturb is disabled
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 201 has ExtensionState: 0
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 201
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 202 has ExtensionState: 0
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 202
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 203 has ExtensionState: 0
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 203
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Extension 204 has ExtensionState: 0
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 204
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: dialparties.agi: Filtered ARG3: 201-202-203-204
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] res_agi.c: <SIP/MagnaVoIP-00000000>AGI Script dialparties.agi completed, returning 0
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:22] NoOp(“SIP/MagnaVoIP-00000000”, “Returned from dialparties with hunt groups to dial”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:23] Set(“SIP/MagnaVoIP-00000000”, “HuntLoop=0”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:24] ExecIf(“SIP/MagnaVoIP-00000000”, “0?Set(HuntMembers=0)”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:25] GotoIf(“SIP/MagnaVoIP-00000000”, “1?a30”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx_builtins.c: Goto (macro-dial,s,28)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:28] Set(“SIP/MagnaVoIP-00000000”, “HuntMember=HuntMember0”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:29] GotoIf(“SIP/MagnaVoIP-00000000”, “1?a32:a35”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx_builtins.c: Goto (macro-dial,s,30)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:30] Set(“SIP/MagnaVoIP-00000000”, “CT_EXTEN=201”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:31] Set(“SIP/MagnaVoIP-00000000”, “DB(CALLTRACE/201)=8007465334”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:32] Goto(“SIP/MagnaVoIP-00000000”, “s,huntstart”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx_builtins.c: Goto (macro-dial,s,40)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:40] NoOp(“SIP/MagnaVoIP-00000000”, “Hunt Dial Start”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:41] Macro(“SIP/MagnaVoIP-00000000”, “dial-hunt-predial-hook,”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial-hunt-predial-hook:1] MacroExit(“SIP/MagnaVoIP-00000000”, “”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-dial:42] Dial(“SIP/MagnaVoIP-00000000”, “SIP/201,10,trM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] netsock2.c: Using SIP RTP TOS bits 184
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] netsock2.c: Using SIP RTP CoS mark 5
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_stack.c: SIP/201-00000001 Internal Gosub(func-apply-sipheaders,s,1) start
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/201-00000001”, “Applying SIP Headers to channel”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/201-00000001”, “SIPHEADERKEYS=”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@func-apply-sipheaders:3] While(“SIP/201-00000001”, “0”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_while.c: Jumping to priority 7
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] pbx.c: Executing [s@func-apply-sipheaders:8] Return(“SIP/201-00000001”, “”) in new stack
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_stack.c: Spawn extension (from-internal, 814, 1) exited non-zero on ‘SIP/201-00000001’
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_stack.c: SIP/201-00000001 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_dial.c: Called SIP/201
[2016-05-20 15:39:24] WARNING[30927][C-00000000] translate.c: no samples for ulawtolin
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘113’ is now Reachable. (14ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘112’ is now Reachable. (28ms / 2000ms)
[2016-05-20 15:39:24] VERBOSE[30927][C-00000000] app_dial.c: SIP/201-00000001 is ringing
[2016-05-20 15:39:24] WARNING[30927][C-00000000] translate.c: no samples for ulawtolin
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘131’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘130’ is now Reachable. (31ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘186’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘MagnaVoIP’ is now Reachable. (20ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘181’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘182’ is now Reachable. (31ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘141’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘142’ is now Reachable. (31ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘143’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘144’ is now Reachable. (32ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘243’ is now Reachable. (17ms / 2000ms)
[2016-05-20 15:39:24] NOTICE[30891] chan_sip.c: Peer ‘242’ is now Reachable. (31ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘241’ is now Reachable. (17ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘244’ is now Reachable. (33ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘162’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘163’ is now Reachable. (29ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘161’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘166’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘167’ is now Reachable. (16ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘164’ is now Reachable. (29ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘220’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘151’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘153’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘152’ is now Reachable. (32ms / 2000ms)
[2016-05-20 15:39:25] NOTICE[30891] chan_sip.c: Peer ‘154’ is now Reachable. (15ms / 2000ms)
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] app_dial.c: SIP/201-00000001 answered SIP/MagnaVoIP-00000000
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:1] Set(“SIP/201-00000001”, “__MACRO_RESULT=”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:2] Set(“SIP/201-00000001”, “CFIGNORE=”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:3] Set(“SIP/201-00000001”, “MASTER_CHANNEL(CFIGNORE)=”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:4] Set(“SIP/201-00000001”, “FORWARD_CONTEXT=from-internal”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:5] Set(“SIP/201-00000001”, “MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:6] Macro(“SIP/201-00000001”, “blkvm-clr,”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-blkvm-clr:1] Set(“SIP/201-00000001”, “SHARED(BLKVM,SIP/MagnaVoIP-00000000)=”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-blkvm-clr:2] Set(“SIP/201-00000001”, “GOSUB_RETVAL=”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/201-00000001”, “”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf(“SIP/201-00000001”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=201)”) in new stack
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf(“SIP/201-00000001”, “0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Front Desk 277Pink)”) in new stack
[2016-05-20 15:39:29] VERBOSE[30937][C-00000000] bridge_channel.c: Channel SIP/201-00000001 joined ‘simple_bridge’ basic-bridge <31f2ab5c-48df-4729-9b98-186ff897684f>
[2016-05-20 15:39:29] VERBOSE[30927][C-00000000] bridge_channel.c: Channel SIP/MagnaVoIP-00000000 joined ‘simple_bridge’ basic-bridge <31f2ab5c-48df-4729-9b98-186ff897684f>