Voicemail Hangs up while caller is leaving message

I am having a problem where when a caller leaves a voicemail message the voicemail application hungs the call up in the middle of the voicemail message.

All the calls are coming in over POTS line. I am using asterisk 1.2.12.1.

need more info- relevant extensions.conf segment, cli output?

It happens with all extensions.

Here is the extensions.conf

[globals]
bob=100
bill=101
mike=102
fred=103
betty=109
wilma=110
fred=111
barney=112
john=120

[incoming]

    exten => s,1,           Answer()
    exten => s,2,           Set(Playcount=0)
    exten => s,3,           Wait(1.5)
    exten => s,4,           Background(Welcome-2-bedrock)
    exten => s,5,           WaitExten(10)

    ;Sales Extension
    exten => 2,1,           Goto(internal,199,1)

    ;Inernal Extensions
exten => _1XX,1,        Goto(internal,${EXTEN},1)

    ;Accounting Extenion
    exten => 3,1,           Goto(internal,1980,1)

    
    ;Directory;
    exten => 8,1,           Directory(default,incoming)

    ;External Voicemail
    exten => 333,1,         Authenticate(1234)
    exten => 333,2,         Goto(internal,250,1)
    exten => 333,102,       HangUp()

    ;Op
    exten => 0,1,           Goto(internal,${op},1)

    ;Invalid
    exten => i,1,           Playback(pbx-invalid)
    exten => i,2,           Goto(incoming,s,1)

;Timeout
    exten => t,1,           Playback(pbx-invalid)
    exten => t,2,           GotoIf(${Playcount}=1?:4|3))
    exten => t,3,           HangUp()
    exten => t,4,           Set(Playcount=1)
    exten => t,5,           Goto(incoming,s,4)

[internal]

    ;Internal Extensions
    exten => 100,1,         Macro(oneline,SIP/${bob},20,r)
    exten => 101,1,         Macro(oneline,SIP/${bill})
    exten => 102,1,         Macro(oneline,SIP/${mike})
    exten => 103,1,         Macro(oneline,SIP/${fred})
    exten => 111,1,         Macro(oneline,SIP/${tedo})
    exten => 109,1,         Macro(oneline,SIP/${betty})
    exten => 110,1,         Macro(oneline,SIP/${wilma})
    exten => 120,1,         Macro(oneline,SIP/${john})

exten => 198,1,         Macro(twoline,SIP/${wilma},SIP/${betty})
    exten => 199,1,         Macro(twoline,SIP/${fred},SIP/${barney})


    ;Music On Hold
    exten => 700,1,         MusicOnHold(default)

    ;Voice Mail Main
    exten => 250,1,         VoiceMailMain()
    exten => 250,2,         HangUp()

[macro-oneline]

    exten => s,1,   Dial(${ARG1},20,t)
    exten => s,2,   Voicemail(u${MACRO_EXTEN})
    exten => s,3,   Hangup()
    exten => s,102, Voicemail(b${MACRO_EXTEN})
    exten => s,103, Hangup()

[macro-twoline]

    exten => s,1,   Dial(${ARG1} & ${ARG2},20,t)
    exten => s,2,   Voicemail(u${MACRO_EXTEN})
    exten => s,3,   Hangup()
    exten => s,102, Voicemail(b${MACRO_EXTEN})
    exten => s,103, Hangup()

I’ll try to capture the CLI.

that looks fine after a quick once over will check it out more once you post cli…
one thing you can do to shrink that a bit-
instead of having a oneline and twoline macro, just use oneline as follows:

exten => 198,1, Macro(oneline,SIP/${wilma}&SIP/${betty})

this makes ${ARG1} for the macro be: SIP/${wilma}&SIP/${betty}
which creates a valid Dial() statement.

CLI:

Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Oct 9 15:09:23 WARNING[18523]: chan_sip.c:2570 sip_write: Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
Asked to transmit frame type 4, while native formats is 262144 (read/write = 0/0)
– Nobody picked up in 20000 ms
– Nobody picked up in 20000 ms
– Executing VoiceMail(“Zap/1-1”, “u110”) in new stack
– Executing VoiceMail(“Zap/1-1”, “u110”) in new stack
– Playing ‘vm-theperson’ (language ‘en’)
– Playing ‘vm-theperson’ (language ‘en’)
– Playing ‘digits/1’ (language ‘en’)
– Playing ‘digits/1’ (language ‘en’)
– Playing ‘digits/1’ (language ‘en’)
– Playing ‘digits/1’ (language ‘en’)
– Playing ‘digits/0’ (language ‘en’)
– Playing ‘digits/0’ (language ‘en’)
– Playing ‘vm-isunavail’ (language ‘en’)
– Playing ‘vm-isunavail’ (language ‘en’)
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’ in macro ‘oneline’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’ in macro ‘oneline’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’
– Hungup ‘Zap/1-1’
– Hungup 'Zap/1-1’
ds3-pbx*CLI> Oct 9 15:51:59 WARNING[18136]: chan_sip.c:10055 handle_response: Host ‘1.1.1.4’ does not implement 'NOTIFY’
Oct 9 15:51:59 WARNING[18136]: chan_sip.c:10055 handle_response: Host ‘1.1.1.4’ does not implement ‘NOTIFY’
– Starting simple switch on ‘Zap/1-1’
– Starting simple switch on ‘Zap/1-1’
– Executing Answer(“Zap/1-1”, “”) in new stack
– Executing Answer(“Zap/1-1”, “”) in new stack
– Executing Set(“Zap/1-1”, “Playcount=0”) in new stack
– Executing Set(“Zap/1-1”, “Playcount=0”) in new stack
– Executing Wait(“Zap/1-1”, “1.5”) in new stack
– Executing Wait(“Zap/1-1”, “1.5”) in new stack
– Executing BackGround(“Zap/1-1”, “Welcome-2-bedrock”) in new stack
– Executing BackGround(“Zap/1-1”, “Welcome-2-bedrock”) in new stack
– Playing ‘Welcome-2-bedrock’ (language ‘en’)
– Playing ‘Welcome-2-bedrock’ (language ‘en’)
== CDR updated on Zap/1-1
== CDR updated on Zap/1-1
– Executing Goto(“Zap/1-1”, “internal|100|1”) in new stack
– Executing Goto(“Zap/1-1”, “internal|100|1”) in new stack
– Goto (internal,100,1)
– Goto (internal,100,1)
– Executing Macro(“Zap/1-1”, “oneline|SIP/100|20|r”) in new stack
– Executing Macro(“Zap/1-1”, “oneline|SIP/100|20|r”) in new stack
– Executing Dial(“Zap/1-1”, “SIP/100|20|t”) in new stack
– Executing Dial(“Zap/1-1”, “SIP/100|20|t”) in new stack
– Called 100
– Called 100
– SIP/100-081b1108 is ringing
– SIP/100-081b1108 is ringing
– Nobody picked up in 20000 ms
– Nobody picked up in 20000 ms
– Executing VoiceMail(“Zap/1-1”, “u100”) in new stack
– Executing VoiceMail(“Zap/1-1”, “u100”) in new stack
– Playing ‘/var/spool/asterisk/voicemail/default/100/temp’ (language ‘en’)
– Playing ‘/var/spool/asterisk/voicemail/default/100/temp’ (language ‘en’)
– Playing ‘vm-intro’ (language ‘en’)
– Playing ‘vm-intro’ (language ‘en’)
– Playing ‘beep’ (language ‘en’)
– Playing ‘beep’ (language ‘en’)
– Recording the message
– Recording the message
– x=0, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: wav49, 0x816f758
– x=0, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: wav49, 0x816f758
– x=1, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: gsm, 0x81519e0
– x=1, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: gsm, 0x81519e0
– x=2, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: wav, 0x8151ca0
– x=2, open writing: /var/spool/asterisk/voicemail/default/100/tmp/H0hlhV format: wav, 0x8151ca0
– User hung up
– User hung up
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’ in macro ‘oneline’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’ in macro ‘oneline’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’
== Spawn extension (macro-oneline, s, 2) exited non-zero on ‘Zap/1-1’
– Hungup ‘Zap/1-1’
– Hungup ‘Zap/1-1’