[Problem] Sporadisches Problem nach DND-Schaltung

hiasB

Neuer User
Mitglied seit
26 Jul 2011
Beiträge
45
Punkte für Reaktionen
0
Punkte
6
Hallo zusammen,

wir haben sporadisch bei einigen Benutzern das Problem, dass manchmal nach DND Schaltung und Aufhebung der DND Schaltung die Nebenstelle trotzdem nicht erreichbar ist und der Anrufer auf der Voicemail landet, als wäre DND nicht deaktiviert. Das ganze ist sehr ärgerlich weil der Benutzer das gar nicht mitbekommt, das er nicht erreichbar ist.
Ein Reboot des Telefons (Snom 320 FW: 8.4.32) behebt das Problem erst wieder.

Die Telefone sind mit unserer Asterisk (Version: 11.4.0) angebunden.

Hier ein Asterisk SIP Trace der auf das Telefon ging, dass nicht erreichbar war. U.a wurde versucht darauf anzurufen. Die Asterisk warf u.a ein:

Code:
[Apr 30 15:04:34] WARNING[14874][C-00002e0b]: chan_sip.c:22834 handle_response_invite: Received response: "Forbidden" from '"Username2" <sip:[email protected]>;tag=as3750a5ed'
  == Everyone is busy/congested at this time (1:0/0/1)

Hier der weitere Trace:
Code:
Reliably Transmitting (no NAT) to 192.168.115.117:2048:
OPTIONS sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK4f1514fe
Max-Forwards: 70
From: "asterisk" <sip:[email protected]>;tag=as66c139fd
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 11.4.0
Date: Wed, 30 Apr 2014 13:04:16 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


---

<--- SIP read from UDP:192.168.115.117:2048 --->
REGISTER sip:servername SIP/2.0
Via: SIP/2.0/UDP 192.168.115.117:2048;branch=z9hG4bK-n22btcsvj36t;rport
From: "Username1" <sip:user1@servername>;tag=nf2r2kb4a4
To: "Username1" <sip:user1@servername>
Call-ID: 3c267023205f-kg3tkmjv8193
CSeq: 973 REGISTER
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=vhc04qe9>;reg-id=1;q=1.0;+sip.instance="<urn:uuid:d4822633-fe3c-4dc1-8e0c-0004133189D7>";audio;mobility="fixed";duplex="full";description="snom320";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO"
User-Agent: snom320/8.4.32
Allow-Events: dialog
X-Real-IP: 192.168.115.117
Supported: path, gruu
Expires: 3600
Content-Length: 0

<------------->
--- (14 headers 0 lines) ---
Sending to 192.168.115.117:2048 (no NAT)
Sending to 192.168.115.117:2048 (no NAT)

<--- Transmitting (no NAT) to 192.168.115.117:2048 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.115.117:2048;branch=z9hG4bK-n22btcsvj36t;received=192.168.115.117;rport=2048
From: "Username1" <sip:user1@servername>;tag=nf2r2kb4a4
To: "Username1" <sip:user1@servername>;tag=as22def075
Call-ID: 3c267023205f-kg3tkmjv8193
CSeq: 973 REGISTER
Server: Asterisk PBX 11.4.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="1813bb88"
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '3c267023205f-kg3tkmjv8193' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:192.168.115.117:2048 --->
SIP/2.0 403 Use Proxy
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK4f1514fe
From: "asterisk" <sip:[email protected]>;tag=as66c139fd
To: <sip:[email protected]:2048;line=vhc04qe9>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: snom320/8.4.32
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Content-Length: 0

<------------->
--- (11 headers 0 lines) ---
Really destroying SIP dialog '[email protected]:5060' Method: OPTIONS

<--- SIP read from UDP:192.168.115.117:2048 --->
REGISTER sip:servername SIP/2.0
Via: SIP/2.0/UDP 192.168.115.117:2048;branch=z9hG4bK-o59b8bmlnygq;rport
From: "Username1" <sip:user1@servername>;tag=nf2r2kb4a4
To: "Username1" <sip:user1@servername>
Call-ID: 3c267023205f-kg3tkmjv8193
CSeq: 974 REGISTER
Max-Forwards: 70
Contact: <sip:[email protected]:2048;line=vhc04qe9>;reg-id=1;q=1.0;+sip.instance="<urn:uuid:d4822633-fe3c-4dc1-8e0c-0004133189D7>";audio;mobility="fixed";duplex="full";description="snom320";actor="principal";events="dialog";methods="INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,NOTIFY,SUBSCRIBE,PRACK,MESSAGE,INFO"
User-Agent: snom320/8.4.32
Allow-Events: dialog
X-Real-IP: 192.168.115.117
Supported: path, gruu
Authorization: Digest username="user1",realm="asterisk",nonce="1813bb88",uri="sip:servername",response="819544f60d37d28f9b4f639919250f89",algorithm=MD5
Expires: 3600
Content-Length: 0

<------------->
--- (15 headers 0 lines) ---
Sending to 192.168.115.117:2048 (no NAT)
Reliably Transmitting (no NAT) to 192.168.115.117:2048:
OPTIONS sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK1dba2370
Max-Forwards: 70
From: "asterisk" <sip:[email protected]>;tag=as5bb6861c
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 11.4.0
Date: Wed, 30 Apr 2014 13:04:16 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


---

<--- Transmitting (no NAT) to 192.168.115.117:2048 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.115.117:2048;branch=z9hG4bK-o59b8bmlnygq;received=192.168.115.117;rport=2048
From: "Username1" <sip:user1@servername>;tag=nf2r2kb4a4
To: "Username1" <sip:user1@servername>;tag=as22def075
Call-ID: 3c267023205f-kg3tkmjv8193
CSeq: 974 REGISTER
Server: Asterisk PBX 11.4.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 3600
Contact: <sip:[email protected]:2048;line=vhc04qe9>;expires=3600
Date: Wed, 30 Apr 2014 13:04:16 GMT
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '[email protected]:5060' in 6400 ms (Method: NOTIFY)
Reliably Transmitting (no NAT) to 192.168.115.117:2048:
NOTIFY sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK0cc6b755
Max-Forwards: 70
From: "asterisk" <sip:[email protected]>;tag=as5bc2da9f
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX 11.4.0
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:[email protected]
Voice-Message: 0/0 (0/0)

---
Scheduling destruction of SIP dialog '3c267023205f-kg3tkmjv8193' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:192.168.115.117:2048 --->
SIP/2.0 403 Use Proxy
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK1dba2370
From: "asterisk" <sip:[email protected]>;tag=as5bb6861c
To: <sip:[email protected]:2048;line=vhc04qe9>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: snom320/8.4.32
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Content-Length: 0

<------------->
--- (11 headers 0 lines) ---
Really destroying SIP dialog '[email protected]:5060' Method: OPTIONS
[Apr 30 15:04:16] NOTICE[14874]: chan_sip.c:29302 sip_poke_peer: Still have a QUALIFY dialog active, deleting
Retransmitting #1 (no NAT) to 192.168.115.117:2048:
NOTIFY sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK0cc6b755
Max-Forwards: 70
From: "asterisk" <sip:[email protected]>;tag=as5bc2da9f
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX 11.4.0
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:[email protected]
Voice-Message: 0/0 (0/0)

---

<--- SIP read from UDP:192.168.115.117:2048 --->
SIP/2.0 403 Use Proxy
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK0cc6b755
From: "asterisk" <sip:[email protected]>;tag=as5bc2da9f
To: <sip:[email protected]:2048;line=vhc04qe9>
Call-ID: [email protected]:5060
CSeq: 102 NOTIFY
User-Agent: snom320/8.4.32
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Content-Length: 0

<------------->
--- (11 headers 0 lines) ---

<--- SIP read from UDP:192.168.115.117:2048 --->
SIP/2.0 403 Use Proxy
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK0cc6b755
From: "asterisk" <sip:[email protected]>;tag=as5bc2da9f
To: <sip:[email protected]:2048;line=vhc04qe9>
Call-ID: [email protected]:5060
CSeq: 102 NOTIFY
User-Agent: snom320/8.4.32
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Content-Length: 0

  
    -- Executing [305@context:1] Goto("SIP/user2-000041f5", "dialplan,305,1") in new stack
    -- Goto (dialplan,305,1)
    -- Executing [305@dialplan:1] Macro("SIP/user2-000041f5", "incoming,user1,305,0") in new stack
    -- Executing [s@macro-incoming:1] NoOp("SIP/user2-000041f5", "Eingegangener Anruf von 219 auf user1") in new stack
    -- Executing [s@macro-incoming:2] Set("SIP/user2-000041f5", "dnd=") in new stack
    -- Executing [s@macro-incoming:3] GotoIf("SIP/user2-000041f5", "0?dnd:call") in new stack
    -- Goto (macro-incoming,s,4)
    -- Executing [s@macro-incoming:4] GotoIf("SIP/user2-000041f5", "1?single:dual") in new stack
    -- Goto (macro-incoming,s,5)
    -- Executing [s@macro-incoming:5] Set("SIP/user2-000041f5", "DIALCOMMAND=SIP/user1") in new stack
    -- Executing [s@macro-incoming:6] Goto("SIP/user2-000041f5", "weiter") in new stack
    -- Goto (macro-incoming,s,9)
    -- Executing [s@macro-incoming:9] GotoIf("SIP/user2-000041f5", "?cfextern:zweitregel") in new stack
    -- Goto (macro-incoming,s,10)
    -- Executing [s@macro-incoming:10] GotoIf("SIP/user2-000041f5", "0?cfintern:normal") in new stack
    -- Goto (macro-incoming,s,11)
    -- Executing [s@macro-incoming:11] Dial("SIP/user2-000041f5", "SIP/user1,30,tT") in new stack
  == Using SIP RTP CoS mark 5
Audio is at 25108
Adding codec 100003 (ulaw) to SDP
Adding codec 100004 (alaw) to SDP
Adding codec 100008 (g729) to SDP
Adding codec 100002 (gsm) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 192.168.115.117:2048:
INVITE sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK185d0246
Max-Forwards: 70
From: "Username2" <sip:[email protected]>;tag=as3750a5ed
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 INVITE
User-Agent: Asterisk PBX 11.4.0
Date: Wed, 30 Apr 2014 13:04:34 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 331

v=0
o=root 1028136751 1028136751 IN IP4 192.168.115.8
s=Asterisk PBX 11.4.0
c=IN IP4 192.168.115.8
t=0 0
m=audio 25108 RTP/AVP 0 8 18 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv


    -- Called SIP/user1

<--- SIP read from UDP:192.168.115.117:2048 --->
SIP/2.0 403 Use Proxy
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK185d0246
From: "Username2" <sip:[email protected]>;tag=as3750a5ed
To: <sip:[email protected]:2048;line=vhc04qe9>
Call-ID: [email protected]:5060
CSeq: 102 INVITE
User-Agent: snom320/8.4.32
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Content-Length: 0

<------------->
--- (11 headers 0 lines) ---
Transmitting (no NAT) to 192.168.115.117:2048:
ACK sip:[email protected]:2048;line=vhc04qe9 SIP/2.0
Via: SIP/2.0/UDP 192.168.115.8:5060;branch=z9hG4bK185d0246
Max-Forwards: 70
From: "Username2" <sip:[email protected]>;tag=as3750a5ed
To: <sip:[email protected]:2048;line=vhc04qe9>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 ACK
User-Agent: Asterisk PBX 11.4.0
Content-Length: 0


---
[Apr 30 15:04:34] WARNING[14874][C-00002e0b]: chan_sip.c:22834 handle_response_invite: Received response: "Forbidden" from '"Username2" <sip:[email protected]>;tag=as3750a5ed'
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [s@macro-incoming:12] VoiceMail("SIP/user2-000041f5", "305,u|s") in new stack


Log vom Snom Telefon:

Code:
[9] 30/4/2014 14:59:47: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:48: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 14:59:49: webserver::request 984: GET /memstat.htm HTTP/1.1
[6] 30/4/2014 14:59:49: Basic authentication.
[9] 30/4/2014 14:59:49: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:50: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:51: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:52: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:53: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 14:59:53: SIP: recvd Request INVITE/102 ([email protected]:5060/as7a479979/)
[8] 30/4/2014 14:59:53: SIP: route pending packet 1014571/2: udp 192.168.115.8 5060
[8] 30/4/2014 14:59:53: SIP: send Response 403 INVITE ([email protected]:5060/as7a479979/)
[8] 30/4/2014 14:59:53: SIP: recvd Request ACK/102 ([email protected]:5060/as7a479979/)
[9] 30/4/2014 14:59:53: SIP: clear message_repetition 1014571/[email protected]:5060
[9] 30/4/2014 14:59:54: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:55: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:56: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:57: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:58: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 14:59:59: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:00: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:01: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 15:00:02: Sending Keepalive to Watchdog
[9] 30/4/2014 15:00:02: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:03: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:04: SIP: remove pending packet: 1014570 of 2
[9] 30/4/2014 15:00:04: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:05: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:06: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:07: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:08: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:09: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:00:09: webserver::request 985: GET /memstat.htm HTTP/1.1
[6] 30/4/2014 15:00:09: Basic authentication.
[9] 30/4/2014 15:00:10: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:11: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:12: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:13: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:14: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:15: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:00:16: LLDP: Accept lldp packet from router\bridge:020704001FFE440200040307313306020078080231330A0B706F652D737769746368310C5550726F4375727665204A38313635412053776974636820323635302D5057522C207265766973696F6E20482E31302E3130382C20524F4D20482E30382E303220282F73772F636F64652F6275696C642F66697368290E0400140004100E0706001FFE440200020000000000FE0900120F01036C000010FE070012BB01000F04FE080012BB020100E7AEFE070012BB04030026000080DED2BF
[6] 30/4/2014 15:00:16: LLDP: Port Id Subtype:13
[6] 30/4/2014 15:00:16: LLDP: Port Description:13
[6] 30/4/2014 15:00:16: LLDP: Found vlan id for application type:1
[6] 30/4/2014 15:00:16: LLDP: UNTAGGED=1, VLAN=115, Priority=6, DSCP=46
[6] 30/4/2014 15:00:16: VLAN_current=0, Priority_current=0, DSCP_current=0
[9] 30/4/2014 15:00:16: LLDP: Switchport is untagged!
[4] 30/4/2014 15:00:16: LLDP: update dscp: 184
[6] 30/4/2014 15:00:16: LLDP: Update Dscp value '184' -> '184'
[9] 30/4/2014 15:00:16: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:17: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:18: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:19: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:20: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:21: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:22: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:23: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:24: Timer: Registering with timeout of 0 ms
[5] 30/4/2014 15:00:25: send lldp advertisment
[9] 30/4/2014 15:00:25: LLDP: Sent this packet:0180C200000E0004133189D788CC02060501C0A873750410073030303431333331383944373A5031060200B408084E455420504F52540A07736E6F6D3332300C22736E6F6D3B736E6F6D3332302D53495020382E342E33323B6C69643A382E342E33320E0400240024FE0900120F01036C00000FFE070012BB01003303FE080012BB0201000000FE070012BB04700019FE0B0012BB05736E6F6D333230FE160012BB06736E6F6D3332302D53495020382E342E3332FE160012BB07736E6F6D3332302D53495020382E342E3332FE100012BB08303030343133333138394437FE160012BB09736E6F6D20746563686E6F6C6F6779204147FE0B0012BB0A736E6F6D333230FE0B0012BB0B736E6F6D3332300000 with 275 bytes
[9] 30/4/2014 15:00:25: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:25: SIP: remove receive packet: 14179 of 2
[9] 30/4/2014 15:00:25: SIP: remove receive packet: 14180 of 1
[9] 30/4/2014 15:00:26: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:27: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:28: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:29: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:00:30: webserver::request 986: GET /memstat.htm HTTP/1.1
[6] 30/4/2014 15:00:30: Basic authentication.
[9] 30/4/2014 15:00:30: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:31: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 15:00:32: Sending Keepalive to Watchdog
[9] 30/4/2014 15:00:32: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:33: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:34: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:35: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:36: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:37: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:38: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:39: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:40: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:41: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:42: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:43: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:44: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:45: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:00:46: LLDP: Accept lldp packet from router\bridge:020704001FFE440200040307313306020078080231330A0B706F652D737769746368310C5550726F4375727665204A38313635412053776974636820323635302D5057522C207265766973696F6E20482E31302E3130382C20524F4D20482E30382E303220282F73772F636F64652F6275696C642F66697368290E0400140004100E0706001FFE440200020000000000FE0900120F01036C000010FE070012BB01000F04FE080012BB020100E7AEFE070012BB04030026000080DED2BF
[6] 30/4/2014 15:00:46: LLDP: Port Id Subtype:13
[6] 30/4/2014 15:00:46: LLDP: Port Description:13
[6] 30/4/2014 15:00:46: LLDP: Found vlan id for application type:1
[6] 30/4/2014 15:00:46: LLDP: UNTAGGED=1, VLAN=115, Priority=6, DSCP=46
[6] 30/4/2014 15:00:46: VLAN_current=0, Priority_current=0, DSCP_current=0
[9] 30/4/2014 15:00:46: LLDP: Switchport is untagged!
[4] 30/4/2014 15:00:46: LLDP: update dscp: 184
[6] 30/4/2014 15:00:46: LLDP: Update Dscp value '184' -> '184'
[9] 30/4/2014 15:00:46: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:47: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:48: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:49: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:00:50: webserver::request 987: GET /memstat.htm HTTP/1.1
[6] 30/4/2014 15:00:50: Basic authentication.
[9] 30/4/2014 15:00:50: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:51: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:52: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:53: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:54: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:55: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 15:00:56: SIP: recvd Request OPTIONS/102 ([email protected]:5060/as23bb2fba/)
[8] 30/4/2014 15:00:56: SIP: route pending packet 1014572/2: udp 192.168.115.8 5060
[8] 30/4/2014 15:00:56: SIP: send Response 403 OPTIONS ([email protected]:5060/as23bb2fba/)
[9] 30/4/2014 15:00:56: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:57: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:58: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:00:59: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:00: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:01: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 15:01:02: Sending Keepalive to Watchdog
[9] 30/4/2014 15:01:02: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:03: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:03: SIP: remove pending packet: 1014571 of 2
[9] 30/4/2014 15:01:04: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:05: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:06: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:07: Timer: Registering with timeout of 0 ms
[8] 30/4/2014 15:01:08: SIP: recvd Request INVITE/102 ([email protected]:5060/as0a2ef0a1/)
[8] 30/4/2014 15:01:08: SIP: route pending packet 1014573/2: udp 192.168.115.8 5060
[8] 30/4/2014 15:01:08: SIP: send Response 403 INVITE ([email protected]:5060/as0a2ef0a1/)
[8] 30/4/2014 15:01:08: SIP: recvd Request ACK/102 ([email protected]:5060/as0a2ef0a1/)
[9] 30/4/2014 15:01:08: SIP: clear message_repetition 1014573/[email protected]:5060
[9] 30/4/2014 15:01:08: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:09: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:10: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:01:11: webserver::request 988: GET /memstat.htm HTTP/1.1
[6] 30/4/2014 15:01:11: Basic authentication.
[9] 30/4/2014 15:01:11: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:12: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:13: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:14: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:15: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:01:16: LLDP: Accept lldp packet from router\bridge:020704001FFE440200040307313306020078080231330A0B706F652D737769746368310C5550726F4375727665204A38313635412053776974636820323635302D5057522C207265766973696F6E20482E31302E3130382C20524F4D20482E30382E303220282F73772F636F64652F6275696C642F66697368290E0400140004100E0706001FFE440200020000000000FE0900120F01036C000010FE070012BB01000F04FE080012BB020100E7AEFE070012BB04030026000080DED2BF
[6] 30/4/2014 15:01:16: LLDP: Port Id Subtype:13
[6] 30/4/2014 15:01:16: LLDP: Port Description:13
[6] 30/4/2014 15:01:16: LLDP: Found vlan id for application type:1
[6] 30/4/2014 15:01:16: LLDP: UNTAGGED=1, VLAN=115, Priority=6, DSCP=46
[6] 30/4/2014 15:01:16: VLAN_current=0, Priority_current=0, DSCP_current=0
[9] 30/4/2014 15:01:16: LLDP: Switchport is untagged!
[4] 30/4/2014 15:01:16: LLDP: update dscp: 184
[6] 30/4/2014 15:01:16: LLDP: Update Dscp value '184' -> '184'
[9] 30/4/2014 15:01:16: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:17: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:18: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:19: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:20: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:21: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:01:22: webserver::request 989: GET / HTTP/1.1
[6] 30/4/2014 15:01:22: Basic authentication.
[6] 30/4/2014 15:01:22: Authentication triggered. Set flag back.
[9] 30/4/2014 15:01:22: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:23: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:24: Timer: Registering with timeout of 0 ms
[5] 30/4/2014 15:01:25: send lldp advertisment
[9] 30/4/2014 15:01:25: LLDP: Sent this packet:0180C200000E0004133189D788CC02060501C0A873750410073030303431333331383944373A5031060200B408084E455420504F52540A07736E6F6D3332300C22736E6F6D3B736E6F6D3332302D53495020382E342E33323B6C69643A382E342E33320E0400240024FE0900120F01036C00000FFE070012BB01003303FE080012BB0201000000FE070012BB04700019FE0B0012BB05736E6F6D333230FE160012BB06736E6F6D3332302D53495020382E342E3332FE160012BB07736E6F6D3332302D53495020382E342E3332FE100012BB08303030343133333138394437FE160012BB09736E6F6D20746563686E6F6C6F6779204147FE0B0012BB0A736E6F6D333230FE0B0012BB0B736E6F6D3332300000 with 275 bytes
[6] 30/4/2014 15:01:25: webserver::request 990: GET / HTTP/1.1
[6] 30/4/2014 15:01:25: Basic authentication.
[7] 30/4/2014 15:01:26: TaskMon: LCS 1823111/0 recv LPCP took 597 msecs
[7] 30/4/2014 15:01:26: LoopMon: LCS 1823111 took 680 (250/81) msecs, read 1, 4/1 tasks
[9] 30/4/2014 15:01:26: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:01:26: webserver::request 990: GET /normal.css HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 991: GET /fox.css HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 990: GET /img/clr.gif HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 991: GET /img/snom_logo.png HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 992: GET /img/separater.png HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 993: GET /img/corul.gif HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 990: GET /img/corlr.gif HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 991: GET /img/delete.gif HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:26: webserver::request 992: GET /img/edit.gif HTTP/1.1
[6] 30/4/2014 15:01:26: Basic authentication.
[6] 30/4/2014 15:01:27: webserver::request 994: GET /img/corur.gif HTTP/1.1
[6] 30/4/2014 15:01:27: Basic authentication.
[6] 30/4/2014 15:01:27: webserver::request 995: GET /img/corll.gif HTTP/1.1
[6] 30/4/2014 15:01:27: Basic authentication.
[6] 30/4/2014 15:01:27: webserver::request 990: GET /img/head.png HTTP/1.1
[6] 30/4/2014 15:01:27: Basic authentication.
[9] 30/4/2014 15:01:27: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:28: Timer: Registering with timeout of 0 ms
[9] 30/4/2014 15:01:28: SIP: remove receive packet: 14181 of 3
[6] 30/4/2014 15:01:28: webserver::request 996: GET /favicon.ico HTTP/1.1
[6] 30/4/2014 15:01:28: Basic authentication.
[9] 30/4/2014 15:01:29: Timer: Registering with timeout of 0 ms
[6] 30/4/2014 15:01:29: webserver::request 996: GET /log.htm HTTP/1.1
[6] 30/4/2014 15:01:29: Basic authentication.

Hat jemand eine Idee?

Ich mach paralell jetzt mal ein Update der Snom Firmware.

Danke schon mal.
 
Dazu kann ich leider nicht allzuviel beitragen, da meine Snoms momentan Briefbeschwerer sind. :) Dennoch ...

Setzt der User DND via Telefontaste oder per Action-URL oder sonst wie per Asterisk? Den DND-Status des Telefons könntest du ggf. per action URL direkt am Telefon abfragen womit schon mal gewährleistet wäre, das selbiger bekannt ist.
Zudem gibt es im Snom-Telefon die Variable server managed dnd oder so ähnlich. Ich erinnere mich dunkel, dass es hier zu problemen in Verbindung mit einer anderen Variablen kommen kann. Am besten mal im Snom-Forum nachfragen oder suchen.
Dann ist mir noch aufgefallen, dass der SIP-Port im Telefon nicht gesetzt ist, womit der Port 2048 verwendet wird. Unschön aber vermutlich nicht weiter dramatisch.

VG R.
 
Die Frage ist tatsächlich, wie ihr DND setzt. So wie der Trace aussieht, offenbar am Telefon (nicht von Asterisk verwaltet) - damit wären wir dann in der Welt von SNOM (und ggf. einem Bug dort). da würde ich mal im Snom-Forum nachforschen.
Ich kann nur - aus eigener Erfahrung auch in Umgebungen mit verschiedenen Telefonmodellen unterschiedlicher Hersteller - empfehlen, die DND- und andere Schaltungen (Rufumleitunegn etc.) möglichst serverseitig zu machen, dann "kennt" die Asterisk den Zustand und kann bei jedem Zustandswechsel auch entsprechende Bestätigungen erteilen. Das ging bei Snom entweder mit Action-URLs oder per Keycode (letzteres ist die übliche Strategie bei FreePBX- und darauf aufbaueneden Installationen).
 
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.