Hallo Wzut,
habe den von dir vorgeschlagenen Test durchgeführt - hier das kommentierte verbose 5 Log dazu:
=> 0. FHEM Neustart
2018.07.20 07:48:49 4: mySIP, Listen new PID : 28118
2018.07.20 07:48:49 4: mySIP[28118], my parent is 28111
2018.07.20 07:48:49 4: mySIP[28118], trying to use port 5060
2018.07.20 07:48:50 4: mySIP[28118], register new expire : 2018-07-20 07:53:50
2018.07.20 07:48:50 5: mySIP, readingB:state Val:listen_dtmf
2018.07.20 07:48:50 5: mySIP, readingB:listen_alive Val:28118
2018.07.20 07:48:50 5: mySIP, readingB:expire Val:300
2018.07.20 07:48:50 5: mySIP, readingB:caller Val:none
2018.07.20 07:48:50 5: mySIP, readingB:caller_state Val:waiting
2018.07.20 07:49:49 5: mySIP, listen process 28118 found
=> 1. Anruf
2018.07.20 07:50:03 5: mySIP[28118], SIP_filter : "Tobi" <sip:01727xxxxxx@fritz.box>;tag=941177940FDE6300
2018.07.20 07:50:03 4: mySIP[28118], SIP_filter: caller Tobi, callnr 01727xxxxxx
2018.07.20 07:50:03 5: mySIP, readingB:caller Val:Tobi
2018.07.20 07:50:03 4: mySIP[28118], cb_create : INVITE
2018.07.20 07:50:03 5: mySIP, readingB:caller_nr Val:01727xxxxxx
2018.07.20 07:50:03 5: mySIP[28118], cb_ivite
2018.07.20 07:50:03 5: mySIP, readingS:caller_state Val:ringing
2018.07.20 07:50:04 5: mySIP[28118], cb_est
2018.07.20 07:50:04 5: mySIP, readingS:caller_state Val:established
2018.07.20 07:50:08 5: mySIP[28118], DTMF Event: # - 949 ms
2018.07.20 07:50:11 5: mySIP[28118], DTMF Event: 4 - 209 ms
2018.07.20 07:50:11 5: mySIP[28118], DTMF: 4 , Anz: 2
2018.07.20 07:50:11 5: mySIP[28118], DTMF Event: 2 - 629 ms
2018.07.20 07:50:11 5: mySIP[28118], DTMF: 42 , Anz: 3
2018.07.20 07:50:11 5: mySIP, readingS:dtmf_event Val:42
2018.07.20 07:50:11 5: mySIP[28118], DTMF Event: 2 - 40 ms
2018.07.20 07:50:12 5: mySIP, readingB:caller Val:none
2018.07.20 07:50:12 5: mySIP, readingB:caller_state Val:hangup
2018.07.20 07:50:12 5: mySIP, readingB:caller_time Val:8
2018.07.20 07:50:12 5: mySIP, readingB:caller_nr Val:---
2018.07.20 07:50:12 5: mySIP[28118], DTMF Event: 2 - 330 ms
=> DTMF korrekt eingegeben, DTMF Event, Auto Hangup OK
2018.07.20 07:50:50 5: mySIP, listen process 28118 found
=> 2. Anruf
2018.07.20 07:51:19 5: mySIP[28118], SIP_filter : "Tobi" <sip:01727xxxxxx@fritz.box>;tag=3C6ECEE184926A99
2018.07.20 07:51:19 4: mySIP[28118], SIP_filter: caller Tobi, callnr 01727xxxxxx
2018.07.20 07:51:19 4: mySIP[28118], cb_create : INVITE
2018.07.20 07:51:19 5: mySIP, readingB:caller Val:Tobi
2018.07.20 07:51:19 5: mySIP, readingB:caller_nr Val:01727xxxxxx
2018.07.20 07:51:19 5: mySIP[28118], cb_ivite
2018.07.20 07:51:19 5: mySIP, readingS:caller_state Val:ringing
2018.07.20 07:51:20 5: mySIP[28118], cb_est
2018.07.20 07:51:20 5: mySIP, readingS:caller_state Val:established
2018.07.20 07:51:20 4: mySIP[28118], register new expire : 2018-07-20 07:56:20
2018.07.20 07:51:20 5: mySIP, readingB:state Val:listen_dtmf
2018.07.20 07:51:20 5: mySIP, readingB:listen_alive Val:28118
2018.07.20 07:51:20 5: mySIP, readingB:expire Val:300
2018.07.20 07:51:24 5: mySIP[28118], DTMF Event: # - 429 ms
2018.07.20 07:51:27 5: mySIP[28118], DTMF Event: 9 - 269 ms
2018.07.20 07:51:27 5: mySIP[28118], DTMF: 9 , Anz: 2
2018.07.20 07:51:30 5: mySIP[28118], SIP_bye : HASH(0x27c1c28)
2018.07.20 07:51:30 5: mySIP, readingB:caller Val:none
2018.07.20 07:51:30 5: mySIP, readingB:caller_state Val:hangup
2018.07.20 07:51:30 5: mySIP, readingB:caller_time Val:10
2018.07.20 07:51:30 5: mySIP, readingB:caller_nr Val:---
=> DTMF falsch eingegeben ( nur 1 Ton ), User legt auf
...
=> 3. Anruf
2018.07.20 07:51:43 5: mySIP[28118], SIP_filter : "Tobi" <sip:01727xxxxxx@fritz.box>;tag=0FEFCE7EC5FA86D9
2018.07.20 07:51:43 4: mySIP[28118], SIP_filter: caller Tobi, callnr 01727xxxxxx
2018.07.20 07:51:43 5: mySIP, readingB:caller Val:Tobi
2018.07.20 07:51:43 4: mySIP[28118], cb_create : INVITE
2018.07.20 07:51:43 5: mySIP, readingB:caller_nr Val:01727xxxxxx
2018.07.20 07:51:43 5: mySIP[28118], cb_ivite
2018.07.20 07:51:43 5: mySIP, readingS:caller_state Val:ringing
2018.07.20 07:51:44 5: mySIP[28118], cb_est
2018.07.20 07:51:44 5: mySIP, readingS:caller_state Val:established
2018.07.20 07:51:48 5: mySIP[28118], DTMF Event: # - 959 ms
2018.07.20 07:51:50 5: mySIP, listen process 28118 found
2018.07.20 07:51:53 5: mySIP[28118], DTMF Event: 4 - 559 ms
2018.07.20 07:51:53 5: mySIP[28118], DTMF: 4 , Anz: 2
2018.07.20 07:51:54 5: mySIP[28118], DTMF Event: 4 - 379 ms
2018.07.20 07:51:54 5: mySIP[28118], DTMF Event: 2 - 559 ms
2018.07.20 07:51:54 5: mySIP[28118], DTMF: 42 , Anz: 3
2018.07.20 07:51:54 5: mySIP, readingS:dtmf_event Val:42
2018.07.20 07:51:55 5: mySIP[28118], DTMF Event: 2 - 59 ms
=> DTMF korrekt eingegeben, DTMF Event, KEIN automatischer Hangup
...
=> manueller Hangup nach 30 Sekunden
2018.07.20 07:52:33 5: mySIP[28118], SIP_bye : HASH(0x27be7f8)
2018.07.20 07:52:33 5: mySIP, readingB:caller Val:none
2018.07.20 07:52:33 5: mySIP, readingB:caller_state Val:hangup
2018.07.20 07:52:33 5: mySIP, readingB:caller_time Val:49
2018.07.20 07:52:33 5: mySIP, readingB:caller_nr Val:---
=> SIP Device reset
2018.07.20 07:52:46 4: mySIP, Listen Kill PID : 28118
2018.07.20 07:52:46 4: mySIP, Reset Listen done
2018.07.20 07:52:46 4: mySIP, Listen new PID : 28440
2018.07.20 07:52:46 4: mySIP[28440], my parent is 28111
2018.07.20 07:52:46 4: mySIP[28440], trying to use port 5060
2018.07.20 07:52:47 4: mySIP[28440], register new expire : 2018-07-20 07:57:47
2018.07.20 07:52:47 5: mySIP, readingB:state Val:listen_dtmf
2018.07.20 07:52:47 5: mySIP, readingB:listen_alive Val:28440
2018.07.20 07:52:47 5: mySIP, readingB:expire Val:300
2018.07.20 07:52:47 5: mySIP, readingB:caller Val:none
2018.07.20 07:52:47 5: mySIP, readingB:caller_state Val:waiting
=> 4. Anruf ( nach Reset )
2018.07.20 07:52:58 5: mySIP[28440], SIP_filter : "Tobi" <sip:01727xxxxxx@fritz.box>;tag=B68B7F4EEE4F5441
2018.07.20 07:52:58 4: mySIP[28440], SIP_filter: caller Tobi, callnr 01727xxxxxx
2018.07.20 07:52:58 5: mySIP, readingB:caller Val:Tobi
2018.07.20 07:52:58 4: mySIP[28440], cb_create : INVITE
2018.07.20 07:52:58 5: mySIP, readingB:caller_nr Val:01727xxxxxx
2018.07.20 07:52:58 5: mySIP[28440], cb_ivite
2018.07.20 07:52:58 5: mySIP, readingS:caller_state Val:ringing
2018.07.20 07:52:59 5: mySIP[28440], cb_est
2018.07.20 07:52:59 5: mySIP, readingS:caller_state Val:established
2018.07.20 07:53:03 5: mySIP[28440], DTMF Event: # - 869 ms
2018.07.20 07:53:05 5: mySIP[28440], DTMF Event: 4 - 689 ms
2018.07.20 07:53:05 5: mySIP[28440], DTMF: 4 , Anz: 2
2018.07.20 07:53:06 5: mySIP[28440], DTMF Event: 2 - 489 ms
2018.07.20 07:53:06 5: mySIP[28440], DTMF: 42 , Anz: 3
2018.07.20 07:53:06 5: mySIP, readingS:dtmf_event Val:42
2018.07.20 07:53:07 5: mySIP, readingB:caller Val:none
2018.07.20 07:53:07 5: mySIP, readingB:caller_state Val:hangup
2018.07.20 07:53:07 5: mySIP, readingB:caller_time Val:8
2018.07.20 07:53:07 5: mySIP, readingB:caller_nr Val:---
=> DTMF korrekt, automatischer HANGUP, OK nach Reset
2018.07.20 07:53:46 5: mySIP, listen process 28440 found
2018.07.20 07:54:46 5: mySIP, listen process 28440 found
2018.07.20 07:55:17 4: mySIP[28440], register new expire : 2018-07-20 08:00:17
2018.07.20 07:55:17 5: mySIP, readingB:state Val:listen_dtmf
2018.07.20 07:55:17 5: mySIP, readingB:listen_alive Val:28440
2018.07.20 07:55:17 5: mySIP, readingB:expire Val:300
2018.07.20 07:55:47 5: mySIP, listen process 28440 found
Ich glaube, das war mit der vorherigen Version 1.7 auch schon so. Wenn es schwer zu finden ist, kann ich aber auch noch einmal mit der alten Version testen.
Am Log sieht man ja auch die DTMF Erkennung - insofern würde ich gerne erst einmal auf Tests mit Audiodateien verzichten, da ich auch kein T2S installiert habe.
Gruß
Tobias