[App_rpt-users] asterisk segfault!

Bryan Fields Bryan at bryanfields.net
Thu Jul 21 19:24:46 EDT 2016


On 6/24/16 7:18 PM, Bryan Fields wrote:
> On 6/15/16 3:59 PM, Bryan Fields wrote:
>> I've had asterisk crash twice over the past few weeks.  I can't see any
>> obvious problems in the logging.  I've enabled debug logging and will see what
>> I can find out when/if it happens again.
> 
> It crashed last night!  The only common theme I see is Echolink connected and
> then it had an error of sorts.


It crashed again, and it's an echolink issue.  I had just linked to a friend
on echo link and we were talking.  He keyed up, spoke about 15 seconds, and (i
was mobile) I saw both 220, 440 and 902 mhz repeaters drop out.   The system
restarted in 10 seconds due to me using the systemd scripts to manage it.

When it restarted echolink came back up and we started talking for the next 20
min without issue.

In kern.log
Jul 21 16:58:25 Itchy kernel: [8036215.908665] asterisk[31495]: segfault at
192ec ip b7010840 sp b5001f60 error 4 in chan_echolink.so[b700b000+e000]

In my asterisk log
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Hanging up channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1112987678)
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1112987678
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32708] logger.c:     -- Hungup 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:55] DEBUG[31489] chan_iax2.c: Received iseqno 234 not within window 235->235
> [Jul 21 16:57:57] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:57:59] WARNING[31485] chan_iax2.c: REGISTER-LOG: Sending registration request for '41618'
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Allocate call number
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Registration created on call 3312
> [Jul 21 16:57:59] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:59] DEBUG[31487] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Received OK from Echolink server nawest.echolink.org
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Directory pgm done downloading(partial,compressed), 482 records
> [Jul 21 16:58:02] DEBUG[31490] chan_iax2.c: Received iseqno 249 not within window 250->250
> [Jul 21 16:58:09] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Auto-deactivating generator
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Set channel DAHDI/pseudo-1312874724 to write format ulaw
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Hanging up channel 'DAHDI/pseudo-1312874724'
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1312874724)
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 77, callwait = -1, thirdcall = -1
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1312874724
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:30] VERBOSE[32699] logger.c:     -- Hungup 'DAHDI/pseudo-1312874724'
> [Jul 21 16:57:30] DEBUG[32699] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:30] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:30] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:33] DEBUG[31498] chan_dahdi.c: Requested indication 13 on channel DAHDI/2-1
> [Jul 21 16:57:33] VERBOSE[31498] logger.c: Node Variable dump for node 42032:
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_TXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_NUMLINKS=46
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_LINKS=46,T41170,T27714,T41599,T29902,T42462,T29826,T29901,T29771,T27455,T29827,T29772,T41508,T28183,T4151
> 0,T41629,T28073,T2578,T41535,T29227,T29474,T29475,T42978,T41385,TN7GLV,T42610,T29659,T41373,T42574,T29658,T41538,T27654,T29521,T29522,T41688,T41525,T1980,T
> 3630564,T42393,T1976,T42205,T41237,T40603,T42947,T40696,T42215,T40562
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_ALINKS=1,41170TU
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:     -- 9 variables
> [Jul 21 16:57:34] DEBUG[31487] chan_iax2.c: Received iseqno 238 not within window 239->239
> [Jul 21 16:57:39] WARNING[31482] chan_iax2.c: REGISTER-LOG: Sending registration request for '40821'
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: Allocate call number
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: Registration created on call 12318
> [Jul 21 16:57:39] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:40] DEBUG[31489] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:57:40] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:40] DEBUG[32701] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ETXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:40] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:40] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Auto-deactivating generator
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Set channel DAHDI/pseudo-1312874724 to write format ulaw
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:30] DEBUG[32699] channel.c: Hanging up channel 'DAHDI/pseudo-1312874724'
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1312874724)
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 77, callwait = -1, thirdcall = -1
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1312874724
> [Jul 21 16:57:30] DEBUG[32699] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:30] VERBOSE[32699] logger.c:     -- Hungup 'DAHDI/pseudo-1312874724'
> [Jul 21 16:57:30] DEBUG[32699] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:30] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:30] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:33] DEBUG[31498] chan_dahdi.c: Requested indication 13 on channel DAHDI/2-1
> [Jul 21 16:57:33] VERBOSE[31498] logger.c: Node Variable dump for node 42032:
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_TXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_NUMLINKS=46
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_LINKS=46,T41170,T27714,T41599,T29902,T42462,T29826,T29901,T29771,T27455,T29827,T29772,T41508,T28183,T4151
> 0,T41629,T28073,T2578,T41535,T29227,T29474,T29475,T42978,T41385,TN7GLV,T42610,T29659,T41373,T42574,T29658,T41538,T27654,T29521,T29522,T41688,T41525,T1980,T
> 3630564,T42393,T1976,T42205,T41237,T40603,T42947,T40696,T42215,T40562
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_ALINKS=1,41170TU
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:33] VERBOSE[31498] logger.c:     -- 9 variables
> [Jul 21 16:57:34] DEBUG[31487] chan_iax2.c: Received iseqno 238 not within window 239->239
> [Jul 21 16:57:39] WARNING[31482] chan_iax2.c: REGISTER-LOG: Sending registration request for '40821'
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: Allocate call number
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:39] DEBUG[31482] chan_iax2.c: Registration created on call 12318
> [Jul 21 16:57:39] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:40] DEBUG[31489] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:57:40] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:40] DEBUG[32701] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ETXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:40] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:40] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Hanging up channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-827327442)
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-827327442
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:40] VERBOSE[32702] logger.c:     -- Hungup 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:40] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:40] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Hanging up channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1742131875)
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1742131875
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:41] VERBOSE[32701] logger.c:     -- Hungup 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:41] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:41] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:41] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:41] DEBUG[31496] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel -2
> [Jul 21 16:57:47] WARNING[31487] chan_iax2.c: REGISTER-LOG: Sending registration request for '42032'
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: Allocate call number
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: ip callno count incremented to 5 for 67.215.233.178
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: Registration created on call 10837
> [Jul 21 16:57:47] WARNING[31488] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:47] DEBUG[31490] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:57:49] DEBUG[31491] chan_iax2.c: ip callno count decremented to 4 for 67.215.233.178
> [Jul 21 16:57:50] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Prodding channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format slin
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Prodding channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format slin
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Hanging up channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1431047561)
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1431047561
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32707] logger.c:     -- Hungup 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Hanging up channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1112987678)
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1112987678
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32708] logger.c:     -- Hungup 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:55] DEBUG[31489] chan_iax2.c: Received iseqno 234 not within window 235->235
> [Jul 21 16:57:57] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:57:59] WARNING[31485] chan_iax2.c: REGISTER-LOG: Sending registration request for '41618'
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Allocate call number
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Registration created on call 3312
> [Jul 21 16:57:59] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:59] DEBUG[31487] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Received OK from Echolink server nawest.echolink.org
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Directory pgm done downloading(partial,compressed), 482 records
> [Jul 21 16:58:02] DEBUG[31490] chan_iax2.c: Received iseqno 249 not within window 250->250
> [Jul 21 16:58:09] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Hanging up channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-960634253)
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-960634253
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32712] logger.c:     -- Hungup 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Hanging up channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1502252132)
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1502252132
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32713] logger.c:     -- Hungup 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:20] DEBUG[32714] channel.c: Hanging up channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1050151101)
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1050151101
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:20] VERBOSE[32714] logger.c:     -- Hungup 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Dynamic Loader Starting:
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/modules.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/modules.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/dnsmgr.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/dnsmgr.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] NOTICE[32727] dnsmgr.c: Managed DNS entries will be refreshed every 300 seconds.
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Ping
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Events
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Logoff
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Hangup
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Status
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Setvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Getvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action GetConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UpdateConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Redirect
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Originate
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Command
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ExtensionState
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action AbsoluteTimeout
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxStatus
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxCount
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ListCommands
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UserEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action WaitEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/manager.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Management interface listening on port 5038
> [Jul 21 16:58:35] NOTICE[32727] cdr.c: CDR simple logging enabled.
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Hanging up channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1431047561)
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1431047561
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32707] logger.c:     -- Hungup 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Hanging up channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1112987678)
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1112987678
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32708] logger.c:     -- Hungup 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:55] DEBUG[31489] chan_iax2.c: Received iseqno 234 not within window 235->235
> [Jul 21 16:57:57] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:57:59] WARNING[31485] chan_iax2.c: REGISTER-LOG: Sending registration request for '41618'
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Allocate call number
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Registration created on call 3312
> [Jul 21 16:57:59] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:59] DEBUG[31487] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Received OK from Echolink server nawest.echolink.org
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Directory pgm done downloading(partial,compressed), 482 records
> [Jul 21 16:58:02] DEBUG[31490] chan_iax2.c: Received iseqno 249 not within window 250->250
> [Jul 21 16:58:09] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Hanging up channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-960634253)
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-960634253
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32712] logger.c:     -- Hungup 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Hanging up channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1502252132)
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1502252132
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32713] logger.c:     -- Hungup 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:20] DEBUG[32714] channel.c: Hanging up channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1050151101)
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1050151101
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:20] VERBOSE[32714] logger.c:     -- Hungup 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Dynamic Loader Starting:
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/modules.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/modules.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/dnsmgr.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/dnsmgr.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] NOTICE[32727] dnsmgr.c: Managed DNS entries will be refreshed every 300 seconds.
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Ping
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Events
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Logoff
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Hangup
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Status
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Setvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Getvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action GetConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UpdateConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Redirect
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Originate
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Command
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ExtensionState
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action AbsoluteTimeout
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxStatus
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxCount
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ListCommands
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UserEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action WaitEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/manager.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Management interface listening on port 5038
> [Jul 21 16:58:35] NOTICE[32727] cdr.c: CDR simple logging enabled.
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == RTP Allocating from port range 5000 -> 31000
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == UDPTL allocating from port range 4500 -> 4999
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk PBX Core Initializing
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Registering builtin applications:
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Answer]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Answer'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [BackGround]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'BackGround'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Busy]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Busy'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Congestion]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Congestion'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Goto]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Goto'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [GotoIf]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'GotoIf'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [GotoIfTime]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'GotoIfTime'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [ExecIfTime]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'ExecIfTime'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Hangup]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Hangup'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [NoOp]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'NoOp'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Progress]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Progress'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [ResetCDR]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'ResetCDR'
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:  [Ringing]
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Registered application 'Ringing'
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:40] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Prodding channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format slin
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Auto-deactivating generator
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Set channel DAHDI/pseudo-827327442 to write format ulaw
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:40] DEBUG[32702] channel.c: Hanging up channel 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-827327442)
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-827327442
> [Jul 21 16:57:40] DEBUG[32702] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:40] VERBOSE[32702] logger.c:     -- Hungup 'DAHDI/pseudo-827327442'
> [Jul 21 16:57:40] DEBUG[32702] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:40] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:40] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Prodding channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format slin
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:41] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Auto-deactivating generator
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Set channel DAHDI/pseudo-1742131875 to write format ulaw
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:41] DEBUG[32701] channel.c: Hanging up channel 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1742131875)
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1742131875
> [Jul 21 16:57:41] DEBUG[32701] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:41] VERBOSE[32701] logger.c:     -- Hungup 'DAHDI/pseudo-1742131875'
> [Jul 21 16:57:41] DEBUG[32701] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:41] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:41] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:41] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:41] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:41] DEBUG[31496] chan_dahdi.c: Write returned -1 (Resource temporarily unavailable) on channel -2
> [Jul 21 16:57:47] WARNING[31487] chan_iax2.c: REGISTER-LOG: Sending registration request for '42032'
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: Allocate call number
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: ip callno count incremented to 5 for 67.215.233.178
> [Jul 21 16:57:47] DEBUG[31487] chan_iax2.c: Registration created on call 10837
> [Jul 21 16:57:47] WARNING[31488] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:47] DEBUG[31490] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:57:49] DEBUG[31491] chan_iax2.c: ip callno count decremented to 4 for 67.215.233.178
> [Jul 21 16:57:50] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Using channel -2
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Prodding channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format slin
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Prodding channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format slin
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Prodding channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format slin
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:57:50] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Set channel DAHDI/pseudo-1431047561 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:57:50] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Auto-deactivating generator
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Set channel DAHDI/pseudo-1112987678 to write format ulaw
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:57:50] DEBUG[32707] channel.c: Hanging up channel 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1431047561)
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1431047561
> [Jul 21 16:57:50] DEBUG[32707] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32707] logger.c:     -- Hungup 'DAHDI/pseudo-1431047561'
> [Jul 21 16:57:50] DEBUG[32707] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:50] DEBUG[32708] channel.c: Hanging up channel 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1112987678)
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1112987678
> [Jul 21 16:57:50] DEBUG[32708] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:57:50] VERBOSE[32708] logger.c:     -- Hungup 'DAHDI/pseudo-1112987678'
> [Jul 21 16:57:50] DEBUG[32708] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:57:50] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:57:55] DEBUG[31489] chan_iax2.c: Received iseqno 234 not within window 235->235
> [Jul 21 16:57:57] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:57:59] WARNING[31485] chan_iax2.c: REGISTER-LOG: Sending registration request for '41618'
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Allocate call number
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: ip callno count incremented to 4 for 67.215.233.178
> [Jul 21 16:57:59] DEBUG[31485] chan_iax2.c: Registration created on call 3312
> [Jul 21 16:57:59] WARNING[31484] chan_iax2.c: REGISTER-LOG: registry rereqquest
> [Jul 21 16:57:59] DEBUG[31487] chan_iax2.c: schedule decrement of callno used for 67.215.233.178 in 60 seconds
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Received OK from Echolink server nawest.echolink.org
> [Jul 21 16:58:01] VERBOSE[31480] logger.c:     -- Directory pgm done downloading(partial,compressed), 482 records
> [Jul 21 16:58:02] DEBUG[31490] chan_iax2.c: Received iseqno 249 not within window 250->250
> [Jul 21 16:58:09] DEBUG[31491] chan_iax2.c: ip callno count decremented to 3 for 67.215.233.178
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TU
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31495] logger.c: Node Variable dump for node 40821:
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_LINKS=2,T41618,T3316305
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_NUMALINKS=2
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ALINKS=2,41618TU,3316305TK
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31495] logger.c:     -- 8 variables
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Prodding channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format slin
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Prodding channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format slin
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Set channel DAHDI/pseudo-960634253 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=79)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Set channel DAHDI/pseudo-1502252132 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32712] channel.c: Hanging up channel 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-960634253)
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-960634253
> [Jul 21 16:58:19] DEBUG[32712] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32712] logger.c:     -- Hungup 'DAHDI/pseudo-960634253'
> [Jul 21 16:58:19] DEBUG[32712] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] DEBUG[32713] channel.c: Hanging up channel 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1502252132)
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 78, callwait = -1, thirdcall = -1
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1502252132
> [Jul 21 16:58:19] DEBUG[32713] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:19] VERBOSE[32713] logger.c:     -- Hungup 'DAHDI/pseudo-1502252132'
> [Jul 21 16:58:19] DEBUG[32713] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:19] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TU
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] chan_dahdi.c: Using channel -2
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] VERBOSE[31496] logger.c: Node Variable dump for node 41618:
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMLINKS=2
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_LINKS=2,T40821,T3316305
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_NUMALINKS=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ALINKS=1,40821TK
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_TXKEYED=1
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_ETXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_RXKEYED=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    RPT_AUTOPATCHUP=0
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:    TRANSFERCAPABILITY=SPEECH
> [Jul 21 16:58:19] VERBOSE[31496] logger.c:     -- 9 variables
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Prodding channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format slin
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 160 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Generator got voice, switching to phase locked mode
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=77)
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Auto-deactivating generator
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Set channel DAHDI/pseudo-1050151101 to write format ulaw
> [Jul 21 16:58:19] DEBUG[32714] channel.c: Scheduling timer at 0 sample intervals
> [Jul 21 16:58:20] DEBUG[32714] channel.c: Hanging up channel 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: dahdi_hangup(DAHDI/pseudo-1050151101)
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Hangup: channel: -2 index = 0, normal = 76, callwait = -1, thirdcall = -1
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1050151101
> [Jul 21 16:58:20] DEBUG[32714] chan_dahdi.c: Updated conferencing on -2, with 0 conference users
> [Jul 21 16:58:20] VERBOSE[32714] logger.c:     -- Hungup 'DAHDI/pseudo-1050151101'
> [Jul 21 16:58:20] DEBUG[32714] devicestate.c: Notification of state change to be queued on device/channel DAHDI/pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: No provider found, checking channel drivers for DAHDI - pseudo
> [Jul 21 16:58:20] DEBUG[31471] devicestate.c: Changing state for DAHDI/pseudo - state 2 (In use)
Last log message before segfault

> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Dynamic Loader Starting:
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/modules.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/modules.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/dnsmgr.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/dnsmgr.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] NOTICE[32727] dnsmgr.c: Managed DNS entries will be refreshed every 300 seconds.
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Ping
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Events
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Logoff
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Hangup
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Status
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Setvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Getvar
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action GetConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UpdateConfig
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Redirect
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Originate
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action Command
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ExtensionState
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action AbsoluteTimeout
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxStatus
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action MailboxCount
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action ListCommands
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action UserEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Manager registered action WaitEvent
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Jul 21 16:58:35] DEBUG[32727] config.c: Parsing /etc/asterisk/manager.conf
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Found
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk Management interface listening on port 5038
> [Jul 21 16:58:35] NOTICE[32727] cdr.c: CDR simple logging enabled.
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == RTP Allocating from port range 5000 -> 31000
> [Jul 21 16:58:35] VERBOSE[32727] logger.c:   == UDPTL allocating from port range 4500 -> 4999
> [Jul 21 16:58:35] VERBOSE[32727] logger.c: Asterisk PBX Core Initializing

Any ideas?  It did not write a coredump, so I need to fix that for next time.


-- 
Bryan Fields

727-409-1194 - Voice
http://bryanfields.net





More information about the App_rpt-users mailing list