Since v2.0.2, non-answered calls make my phone continuously ringing

[Mateusz Viste]

Hi,

I updated my AskoziaPBX install to v2.0.2 recently. Since then, any unanswered call is making my phone to ring without stopping.

That is, let's say that I get a call at 13:00:00. Askozia waits for 20 seconds, and redirects the caller to voicemail. However, my phone is not stopping ringing... When I come to home at 18:00, the phone is still ringing, and I have to pickup and hangup to make it stop.

How could I investigate this issue? Below, I am pasting logs from such situation (but I don't see anything wrong there). Any idea?

Code:

Oct 26 14:42:47 asterisk[1423]: VERBOSE[1449]: == Using SIP RTP CoS mark 5
Oct 26 14:42:47 asterisk[1423]: VERBOSE[1449]: == Using SIP VRTP CoS mark 6
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [mateuszviste@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:1] NoOp("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "incoming call from provider: Ippi.fr to mateuszviste") in new stac
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [mateuszviste@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:2] Set("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "HASVOICEMAIL="yes"") in new stack
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [mateuszviste@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:3] Set("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "SENDNOTIFICATIONS="mateuszmonika@viste-family.net"") in new stack
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [mateuszviste@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:4] Set("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "NOVOICEMAILWHENBUSY="yes"") in new stack
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [mateuszviste@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:5] Macro("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "main,SIP/101,101,0355675706,to,20") in new stack
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Executing [s@macro-main:1] Dial("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "SIP/101,20,to") in new stack
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: == Using SIP RTP CoS mark 5
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: == Using SIP VRTP CoS mark 6
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- Called 101
Oct 26 14:42:47 asterisk[1423]: VERBOSE[6975]: -- SIP/101-0000002c is ringing
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Nobody picked up in 20000 ms
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Executing [s@macro-main:2] NoOp("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "DIALSTATUS: NOANSWER") in new stack
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Executing [s@macro-main:3] Goto("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "s-NOANSWER,1") in new stack
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Goto (macro-main,s-NOANSWER,1)
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Executing [s-NOANSWER@macro-main:1] Goto("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "noanswer,1") in new stack
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Goto (macro-main,noanswer,1)
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Executing [noanswer@macro-main:1] GotoIf("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "1?vm-noanswer,1") in new stack
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Goto (macro-main,vm-noanswer,1)
Oct 26 14:43:07 asterisk[1423]: VERBOSE[6975]: -- Executing [vm-noanswer@macro-main:1] Wait("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "1") in new stack
Oct 26 14:43:08 asterisk[1423]: VERBOSE[6975]: -- Executing [vm-noanswer@macro-main:2] Set("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "MISSEDCAUSE=no-answer") in new stack
Oct 26 14:43:08 asterisk[1423]: VERBOSE[6975]: -- Executing [vm-noanswer@macro-main:3] VoiceMail("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "101,u,0355675706") in new stack
Oct 26 14:43:09 asterisk[1423]: VERBOSE[6975]: -- Playing 'vm-theperson.ulaw' (language 'fr-ca')
Oct 26 14:43:10 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/0.ulaw' (language 'fr-ca')
Oct 26 14:43:11 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/3.ulaw' (language 'fr-ca')
Oct 26 14:43:12 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/5.ulaw' (language 'fr-ca')
Oct 26 14:43:12 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/5.ulaw' (language 'fr-ca')
Oct 26 14:43:13 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/6.ulaw' (language 'fr-ca')
Oct 26 14:43:14 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/7.ulaw' (language 'fr-ca')
Oct 26 14:43:14 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/5.ulaw' (language 'fr-ca')
Oct 26 14:43:15 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/7.ulaw' (language 'fr-ca')
Oct 26 14:43:16 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/0.ulaw' (language 'fr-ca')
Oct 26 14:43:17 asterisk[1423]: VERBOSE[6975]: -- Playing 'digits/6.ulaw' (language 'fr-ca')
Oct 26 14:43:17 asterisk[1423]: VERBOSE[6975]: -- Playing 'vm-isunavail.ulaw' (language 'fr-ca')
Oct 26 14:43:19 asterisk[1423]: VERBOSE[6975]: -- Playing 'vm-intro.ulaw' (language 'fr-ca')
Oct 26 14:43:26 asterisk[1423]: VERBOSE[6975]: -- Playing 'beep.ulaw' (language 'fr-ca')
Oct 26 14:43:26 asterisk[1423]: VERBOSE[6975]: -- Recording the message
Oct 26 14:43:26 asterisk[1423]: VERBOSE[6975]: -- x=0, open writing: /var/asterisk/spool/voicemail/default/101/tmp/cZP4iw format: wav49, 0x81f1420
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: -- User hung up
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Parsing '/var/asterisk/spool/voicemail/default/101/INBOX/msg0000.txt':
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Found
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Parsing '/var/asterisk/spool/voicemail/default/101/INBOX/msg0000.txt':
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Found
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Parsing '/var/asterisk/spool/voicemail/default/101/INBOX/msg0000.txt':
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Found
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Spawn extension (macro-main, vm-noanswer, 3) exited non-zero on 'SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b' in macro 'main'
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: == Spawn extension (SIP-PROVIDER-2719620674c3ccb4253cd4-incoming, mateuszviste, 5) exited non-zero on 'SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b'
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: -- Executing [h@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:1] NoOp("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "incoming call from provider: Ippi.fr to h") in new stack
Oct 26 14:43:40 asterisk[1423]: VERBOSE[6975]: -- Executing [h@SIP-PROVIDER-2719620674c3ccb4253cd4-incoming:2] Set("SIP/SIP-PROVIDER-2719620674c3ccb4253cd4-0000002b", "HASVOICEMAIL="yes"") in new stack
Oct 26 14:43:41 msmtp: host=192.168.195.242 tls=off auth=on user=askoziapbx from=[hidden] recipients=[hidden] mailsize=31109 smtpstatus=250 smtpmsg='250 2.0.0 Ok: queued as E6125A15D' exitcode=EX_OK

[giovanni.v]

I updated my AskoziaPBX install to v2.0.2 recently. Since then, any unanswered call is making my phone to ring without stopping.

That issue happened to me once (using r1701) but then never been able to reproduce the problem. Also for me nothing wrong from the logs.

[Mateusz Viste]

That issue happened to me once (using r1701) but then never been able to reproduce the problem. Also for me nothing wrong from the logs.

Ah, too bad then :-/
I am using the official v2.0.2 downloaded from Askozia's website, and I have this problem at every single call that goes unanswered...
Also, I have this issue on two different phones... I've no idea how to debug this situation, and guess that a downgrade to v2.0.1 will be my only hope :-P

[Michael]

Let me know if the downgrade fixes this.

Also, which storage services or custom code do you have running if any?