[Gelöst] Asterisk 1.4.2x, subscribeMWI und stark verzögertes NOTIFY

[detejo]

folgendes Szenario:

meine IP Telefone (Siemens S675 IP) am eigenen Asterisk signalisieren MWI nur einmal, also genau dann, nachdem sie sich neu angemeldet haben - das sieht dann im DEBUG auf der CLI so aus:

<------------>
Reliably Transmitting (no NAT) to 192.168.xx.75:5060:
NOTIFY sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.xx.6:5060;branch=z9hG4bK4218f0b1;rport
From: "asterisk" <sip:asterisk@myasterisk>;tag=as52fa1cbe
To: <sip:sip:[email protected]:5060>;tag=1549960322
Contact: <sip:[email protected]>
Call-ID: 1915451866@192_168_xx_75
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 94

Messages-Waiting: yes
Message-Account: sip:asterisk@myasterisk
Voice-Message: 3/3 (0/0)

---
maxvdr*CLI> 
<--- SIP read from 192.168.xx.75:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.xx.6:5060;branch=z9hG4bK4218f0b1;rport=5060
From: "21" <sip:21@myasterisk>;tag=as52fa1cbe
To: "21" <sip:21@myasterisk>;tag=1549960322
Call-ID: 1915451866@192_168_xx_75
CSeq: 102 NOTIFY
Contact: <sip:[email protected]:5060>
Supported: replaces
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

funktioniert also - das NOTIFY wird abgeschickt und vom Endgerät auch bestätigt - Anzahl der Messages wird geupdated und Lampe blinkt.

Jetzt spreche ich von extern eine weitere Nachricht auf die Mailbox - aber das Endgerät reagiert nicht.
Das ist keine Überraschung, denn auf der CLI ist ersichtlich, dass vom * kein erneutes NOTIFY als Reaktion verschickt wird.

CLI> voicemail show users
Context    Mbox  User                      Zone       NewMsg
default    21    Mailbox 21                                 4

neue Nachricht in der mailbox ist da - aber kein NOTIFY - zumindest nicht in absehbarer Zeit.
Manchmal kommt es nach 0,5 bis max. 1 Stunde - warum dann und nicht gleich?

passender Auszug aus der sip.conf:

[21]
type=friend
username=21

mailbox=21@default
subscribemwi=yes

allow=alaw
allow=g722
allow=gsm
secret=mysecret
host=dynamic
qualify=yes
context=sip-intern
canreinvite=yes
nat=no
dtmfmode=rfc2833

und Teil der voicemail.conf:

[default]
21 => xxxx,Mailbox 21,21@localhost

da kein erneutes NOTIFY bei neuer Nachricht erfolgt, schiebe ich die Ursache mal auf den Asterisk und stelle die Frage erneut in diesem Unterforum.

hat jemand eine Idee - bekannter Bug oder aber Fehler in meiner *-Konfiguration?

 

[detejo]

ok - ich habe mal weiter geforscht - die Subscription für MWI wird zum Zeitpunkt X - hier 8:02 Uhr erneuert:

[Aug  4 08:02:06] VERBOSE[5266] logger.c: --- (14 headers 0 lines) ---
[Aug  4 08:02:06] VERBOSE[5266] logger.c: Found peer '21'
[Aug  4 08:02:06] VERBOSE[5266] logger.c: Looking for 21 in from-sip (domain myasterisk)
[Aug  4 08:02:06] DEBUG[5266] chan_sip.c: Adding subscription for mailbox notification - peer 21 Mailbox 21@default
[Aug  4 08:02:06] VERBOSE[5266] logger.c: Scheduling destruction of SIP dialog '2043688016@192_168_XX_75' in 3610000 ms (Method: SUBSCRIBE)
[Aug  4 08:02:06] VERBOSE[5266] logger.c:
<--- Transmitting (no NAT) to 192.168.XX.75:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.XX.75:5060;branch=z9hG4bK3b7f8cbefc1a98f33b635f2552428d8;received=192.168.XX.75;rport=5060
From: "21" <sip:21@myasterisk>;tag=1368630736
To: "21" <sip:21@myasterisk>;tag=as22fbb58c
Call-ID: 2043688016@192_168_XX_75
CSeq: 1161530821 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:[email protected]>;expires=3600
Content-Length: 0

eine Statusänderung findet statt, wenn die 3600s abgelaufen sind - also nach einer Stunde - 9:02 Uhr.
Vorher sendet der * einfach nichts, was mit MWI NOTIFY zu tun hat, auch wenn sich ander Mailbox etwas tut (neue Nachricht oder eine gelöscht) - nach ungefähr einer Stunde dann:

[Aug  4 09:01:39] VERBOSE[5266] logger.c: --- (14 headers 0 lines) ---
[Aug  4 09:01:39] VERBOSE[5266] logger.c: Found peer '21'
[Aug  4 09:01:39] VERBOSE[5266] logger.c: Looking for 21 in from-sip (domain myasterisk)
[Aug  4 09:01:39] DEBUG[5266] chan_sip.c: Adding subscription for mailbox notification - peer 21 Mailbox 21@default
[Aug  4 09:01:39] VERBOSE[5266] logger.c: Scheduling destruction of SIP dialog '2043688016@192_168_XX_75' in 3610000 ms (Method: SUBSCRIBE)
[Aug  4 09:01:39] VERBOSE[5266] logger.c:
<--- Transmitting (no NAT) to 192.168.XX.75:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.XX.75:5060;branch=z9hG4bKfcbd041858774c19af68f27de3b717d1;received=192.168.XX.75;rport=5060
From: "21" <sip:21@myasterisk>;tag=1368630736
To: "21" <sip:21@mayasterisk>;tag=as22fbb58c
Call-ID: 2043688016@192_168_XX_75
CSeq: 1161530822 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:[email protected]>;expires=3600
Content-Length: 0


<------------>
[Aug  4 09:01:39] VERBOSE[5266] logger.c: Reliably Transmitting (no NAT) to 192.168.XX.75:5060:
NOTIFY sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.XX.6:5060;branch=z9hG4bK726b7ee7;rport
From: "asterisk" <sip:asterisk@myasterisk>;tag=as22fbb58c
To: <sip:sip:[email protected]:5060>;tag=1368630736
Contact: <sip:[email protected]>
Call-ID: 2043688016@192_168_XX_75
CSeq: 107 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 94

Messages-Waiting: yes
Message-Account: sip:asterisk@myasterisk
Voice-Message: 7/4 (0/0)

wenn die Subscription erneuert wird - nur dann gibt's ein NOTIFY.
kommt die Subscribe-Zeit vom Endgerät oder kann ich die behelfsweise verkürzen?

Was könnte ich sonst noch probieren?

 

[detejo]

es ist ein * BUG:

http://bugs.digium.com/view.php?id=12828

qualify=no

ist der Workaround.