"The best business telephones in the world."

Hello,

we've some troubles with the comibnation Snom D345, Snom EHS Advanced and Plantronics CS540 headset and AskoziaPBX (Asterisk).

Mainly everythng is working fine. Audio ok. Calls are signalised correctly and normal call answer is working.

At some time calls get dropped by accepting the call over the headset.

In log we receive following:

sending 'DTGRING' to wireless headset 

and shortly after

SIP: recv CANCEL 

Any Ideas?

Thank you.

BR

2017-02-02 13:10:36 User.Info 172.16.2.10 Feb 2 13:10:35.841 snomD345-8520AC 0004138520AC [INFO ] GUI: GUI: Line-Seize subscription was not sent

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:35.843 snomD345-8520AC 0004138520AC [DEBUG1] SIP: remove pending packet: 1013439, all 8

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:35.846 snomD345-8520AC 0004138520AC [DEBUG1] SIP: route pending packet 1013440: entry=Udp:172.16.2.1:5060 all 8

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:35.846 snomD345-8520AC 0004138520AC [DEBUG0] SIP: Use Connection Udp:172.16.2.1:5060 for packet 1013440

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:35.846 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 180 INVITE (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:36 Mail.Info 172.16.2.10 Feb 2 13:10:35.849 snomD345-8520AC 0004138520AC [INFO ] SIP: Dialog -125/237/1 going to early

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.854 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::setSpeakerDefault: 6 8

2017-02-02 13:10:36 Kernel.Debug 172.16.2.10 Feb 2 13:10:35.857 snomD345-8520AC 0004138520AC [DEBUG1] PHN: Goto Best State from 'pIdle' (28), force 1

2017-02-02 13:10:36 Local3.Debug 172.16.2.10 Feb 2 13:10:35.858 snomD345-8520AC 0004138520AC [DEBUG0] UXM: Request backlight for module -1 with value 15

2017-02-02 13:10:36 Local3.Debug 172.16.2.10 Feb 2 13:10:35.859 snomD345-8520AC 0004138520AC [DEBUG0] UXM: Send command: B

2017-02-02 13:10:36 Local3.Debug 172.16.2.10 Feb 2 13:10:35.859 snomD345-8520AC 0004138520AC [DEBUG1] UXM: Send bytes 0x42 0x64

2017-02-02 13:10:36 Local3.Debug 172.16.2.10 Feb 2 13:10:35.859 snomD345-8520AC 0004138520AC [DEBUG0] UXM: 2 Bytes sent

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.860 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::setSpeakerDestination: 7

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.861 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::setVolume: 10

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.862 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::setMicroVolume: 0 0

2017-02-02 13:10:36 User.Info 172.16.2.10 Feb 2 13:10:35.862 snomD345-8520AC 0004138520AC [INFO ] GUI: speaker_mixer: 2

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.862 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::setSpeakerMixer: 2

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.863 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: Synthesizer Command: PLAY 50 20 440

PLAY 50 20 523

PLAY 50 20 392

LOOP 2 4 0

PLAY 500 0 0

LOOP 4 2 1

PLAY 2000 0 0

LOOP 6 0 2

2017-02-02 13:10:36 Kernel.Debug 172.16.2.10 Feb 2 13:10:35.873 snomD345-8520AC 0004138520AC [DEBUG1] PHN: Goto Best State from 'pRinging' (10), force 0

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:35.873 snomD345-8520AC 0004138520AC [DEBUG1] SIP: RTP: set_port - ports: 49152 65534, con 237

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:35.874 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: MediaIpc::rtpSetup: rtp: RP237 rtcp: RC237 start: 49152 end: 65534

2017-02-02 13:10:36 Kernel.Debug 172.16.2.10 Feb 2 13:10:35.897 snomD345-8520AC 0004138520AC [DEBUG0] PHN: Request lookup for number sip:01713665218@172.16.2.1

2017-02-02 13:10:36 System2.Info 172.16.2.10 Feb 2 13:10:35.909 snomD345-8520AC 0004138520AC [INFO ] MEDIA: CAudioSubsystem::setAudioMode 0->7

2017-02-02 13:10:36 System2.Info 172.16.2.10 Feb 2 13:10:35.969 snomD345-8520AC 0004138520AC [INFO ] MEDIA: playstate 0->2

2017-02-02 13:10:36 System2.Info 172.16.2.10 Feb 2 13:10:36.060 snomD345-8520AC 0004138520AC [INFO ] MEDIA: setSynthProgram

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:36.061 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: Socket 0x368748/noname: state connected(5)

2017-02-02 13:10:36 System2.Debug 172.16.2.10 Feb 2 13:10:36.076 snomD345-8520AC 0004138520AC [DEBUG0] MEDIA: Socket 0x368a98/noname: state connected(5)

2017-02-02 13:10:36 Kernel.Info 172.16.2.10 Feb 2 13:10:36.076 snomD345-8520AC 0004138520AC [INFO ] PHN: sending 'DTGRING' to wireless headset

2017-02-02 13:10:36 Mail.Debug 172.16.2.10 Feb 2 13:10:36.353 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 180 INVITE (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:37 Mail.Debug 172.16.2.10 Feb 2 13:10:37.354 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 180 INVITE (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:39 Kernel.Info 172.16.2.10 Feb 2 13:10:38.728 snomD345-8520AC 0004138520AC [INFO ] PHN: LLDP: Skipped packet (282 bytes), not from router/bridge

2017-02-02 13:10:39 Kernel.Info 172.16.2.10 Feb 2 13:10:39.064 snomD345-8520AC 0004138520AC [INFO ] PHN: sending 'DTGRING' to wireless headset

2017-02-02 13:10:39 Mail.Debug 172.16.2.10 Feb 2 13:10:39.354 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 180 INVITE (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:40.813 snomD345-8520AC 0004138520AC [DEBUG1] SIP: remove receive packet: 13216, all 5

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:40.953 snomD345-8520AC 0004138520AC [DEBUG1] SIP: remove receive packet: 13217, all 4

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:41.340 snomD345-8520AC 0004138520AC [DEBUG1] SIP: recv OPTIONS (102: 6983709918ca86753e11bbdc62618660@172.16.2.1:5060) <- Udp:172.16.2.1:5060

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:41.347 snomD345-8520AC 0004138520AC [DEBUG1] SIP: route pending packet 1013441: entry=Udp:172.16.2.1:5060 all 9

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:41.347 snomD345-8520AC 0004138520AC [DEBUG0] SIP: Use Connection Udp:172.16.2.1:5060 for packet 1013441

2017-02-02 13:10:41 Mail.Debug 172.16.2.10 Feb 2 13:10:41.347 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 200 OPTIONS (102: 6983709918ca86753e11bbdc62618660@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:42 Kernel.Info 172.16.2.10 Feb 2 13:10:42.065 snomD345-8520AC 0004138520AC [INFO ] PHN: sending 'DTGRING' to wireless headset

2017-02-02 13:10:43 Kernel.Info 172.16.2.10 Feb 2 13:10:42.506 snomD345-8520AC 0004138520AC [INFO ] PHN: LLDP: Skipped packet (282 bytes), not from router/bridge

2017-02-02 13:10:43 Kernel.Info 172.16.2.10 Feb 2 13:10:42.509 snomD345-8520AC 0004138520AC [INFO ] PHN: LLDP: Skipped packet (282 bytes), not from router/bridge

2017-02-02 13:10:43 Mail.Debug 172.16.2.10 Feb 2 13:10:43.354 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 180 INVITE (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:45 Kernel.Info 172.16.2.10 Feb 2 13:10:45.074 snomD345-8520AC 0004138520AC [INFO ] PHN: sending 'DTGRING' to wireless headset

2017-02-02 13:10:46 Kernel.Info 172.16.2.10 Feb 2 13:10:46.185 snomD345-8520AC 0004138520AC [INFO ] PHN: LLDP: Skipped packet (282 bytes), not from router/bridge

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.315 snomD345-8520AC 0004138520AC [DEBUG1] SIP: recv CANCEL (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) <- Udp:172.16.2.1:5060

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.323 snomD345-8520AC 0004138520AC [DEBUG1] SIP: route pending packet 1013442: entry=Udp:172.16.2.1:5060 all 10

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.323 snomD345-8520AC 0004138520AC [DEBUG0] SIP: Use Connection Udp:172.16.2.1:5060 for packet 1013442

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.323 snomD345-8520AC 0004138520AC [DEBUG0] SIP: send 200 CANCEL (102: 0726bb2827542e3d36d945547977e253@172.16.2.1:5060) -> Udp:172.16.2.1:5060

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.326 snomD345-8520AC 0004138520AC [DEBUG1] SIP: remove pending packet: 1013440, all 10

2017-02-02 13:10:46 Mail.Debug 172.16.2.10 Feb 2 13:10:46.330 snomD345-8520AC 0004138520AC [DEBUG1] SIP: route pending packet 1013443: entry=Udp:172.16.2.1:5060 all 10

Dear Luca,

I'll give this firmware a try in the next days. 

Thank you!

BR

This issue occurs with firmware 8.9.3.46 (latest from Snom website).and with earlier version 8.9.3.33