Hauptmenü

Modul 96_SIP

Begonnen von Wzut, 19 Februar 2017, 19:10:09

Vorheriges Thema - Nächstes Thema

plin

Und schneide mehrere Anrufe auf diese Weise mit. Neben der Dauer ist auch der Absatand zwischen den Tastendrücken interessant.
Pack am Besten auch noch ein list des Devices mit dazu, damit wir sehen was aktuell eingestellt ist.
FHEM1 (Main) Raspi4 mit CUL, Homematic, SDUINO 433/OOK, zentrale Steuerung
FHEM2 (Keller) x86 mit CUL/hmland, IP-basierte Module
FHEM3 (Erdgeschoss) Raspi2 mit SDUINO 868/GFSK
FHEM4 (Hausanschlussraum), USV und OBIS-Modul
FHEM5 (Docker) mit FHEM2FHEM, InfluxDB

Kurt77

Hallo plin,
hier das log:


2020.08.28 21:35:57 4: MySipClient, Listen new PID : 19168
2020.08.28 21:35:57 4: MySipClient[19168], my parent is 901
2020.08.28 21:35:57 4: MySipClient[19168], trying to use port 5060
2020.08.28 21:35:57 4: MySipClient[19168], register new expire : 2020-08-28 21:40:57
2020.08.28 21:35:57 5: MySipClient[19168], not converted - using cache/de901947c29afa3f195b50e87289aa4c.alaw from cache
2020.08.28 21:35:57 5: MySipClient[19168], audio file cache/de901947c29afa3f195b50e87289aa4c.alaw found
2020.08.28 21:35:57 5: MySipClient[19168], not converted - using cache/ab7acf820e3b2e2fc76c4012a5c7a991.alaw from cache
2020.08.28 21:35:57 5: MySipClient[19168], audio file cache/ab7acf820e3b2e2fc76c4012a5c7a991.alaw found
2020.08.28 21:35:57 4: MySipClient[19168], using cache/de901947c29afa3f195b50e87289aa4c.alaw for audio_dtmf
2020.08.28 21:35:57 4: MySipClient[19168], using cache/ab7acf820e3b2e2fc76c4012a5c7a991.alaw for audio_ok
2020.08.28 21:35:57 5: MySipClient, readingB:state Val:listen_dtmf
2020.08.28 21:35:57 5: MySipClient, readingB:listen_alive Val:19168
2020.08.28 21:35:57 5: MySipClient, readingB:expire Val:300
2020.08.28 21:36:18 5: MySipClient[19168], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=ACD3D105C3B5C8B4
2020.08.28 21:36:18 4: MySipClient[19168], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.08.28 21:36:18 4: MySipClient[19168], cb_create : INVITE
2020.08.28 21:36:18 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.08.28 21:36:18 5: MySipClient, readingB:caller_nr Val:**610
2020.08.28 21:36:18 5: MySipClient, readingB:caller_name Val:Buero
2020.08.28 21:36:18 5: MySipClient, readingB:caller_time Val:0
2020.08.28 21:36:18 5: MySipClient, readingB:caller_state Val:calling
2020.08.28 21:36:18 5: MySipClient[19168], cb_invite_dtmf
2020.08.28 21:36:18 5: MySipClient, readingS:caller_state Val:ringing
2020.08.28 21:36:21 5: MySipClient[19168], cb_est_dtmf
2020.08.28 21:36:21 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:36:21 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:28 5: MySipClient[19168], DTMF Event: 9 - 86 ms
2020.08.28 21:36:42 5: MySipClient[19168], SIP_bye : HASH(0x4c4bdd8)
2020.08.28 21:36:42 5: MySipClient, readingB:caller Val:none
2020.08.28 21:36:42 5: MySipClient, readingB:caller_state Val:hangup
2020.08.28 21:36:42 5: MySipClient, readingB:caller_time Val:21
2020.08.28 21:36:42 5: MySipClient, readingB:caller_nr Val:---
2020.08.28 21:36:42 5: MySipClient, readingB:caller_name Val:---
2020.08.28 21:36:42 5: MySipClient[19168], while dtmf_loop : dtmfloop : 0 , byebye : 1
2020.08.28 21:36:42 5: MySipClient[19168], aufgelegt
2020.08.28 21:36:42 5: MySipClient[19168], while dtmf_loop, okloopbye : 0 , byebye : 1
2020.08.28 21:36:42 5: MySipClient[19168], end while dtmf_loop, byebye : 1
2020.08.28 21:36:42 5: MySipClient[19168], while(1)
2020.08.28 21:36:55 5: MySipClient[19168], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=A520D2519865B942
2020.08.28 21:36:55 4: MySipClient[19168], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.08.28 21:36:55 4: MySipClient[19168], cb_create : INVITE
2020.08.28 21:36:55 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.08.28 21:36:55 5: MySipClient, readingB:caller_nr Val:**610
2020.08.28 21:36:55 5: MySipClient, readingB:caller_name Val:Buero
2020.08.28 21:36:55 5: MySipClient, readingB:caller_time Val:0
2020.08.28 21:36:55 5: MySipClient, readingB:caller_state Val:calling
2020.08.28 21:36:55 5: MySipClient[19168], cb_invite_dtmf
2020.08.28 21:36:55 5: MySipClient, readingS:caller_state Val:ringing
2020.08.28 21:36:57 5: MySipClient, listen process 19168 found
2020.08.28 21:36:58 5: MySipClient[19168], cb_est_dtmf
2020.08.28 21:36:58 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:58 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:37:06 5: MySipClient[19168], DTMF Event: 8 - 28 ms
2020.08.28 21:37:13 5: MySipClient[19168], SIP_bye : HASH(0x4dc3028)
2020.08.28 21:37:13 5: MySipClient, readingB:caller Val:none
2020.08.28 21:37:13 5: MySipClient, readingB:caller_state Val:hangup
2020.08.28 21:37:13 5: MySipClient, readingB:caller_time Val:15
2020.08.28 21:37:13 5: MySipClient, readingB:caller_nr Val:---
2020.08.28 21:37:13 5: MySipClient, readingB:caller_name Val:---
2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop : dtmfloop : 0 , byebye : 1
2020.08.28 21:37:13 5: MySipClient[19168], aufgelegt
2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop, okloopbye : 0 , byebye : 1
2020.08.28 21:37:13 5: MySipClient[19168], end while dtmf_loop, byebye : 1
2020.08.28 21:37:13 5: MySipClient[19168], while(1)
2020.08.28 21:37:35 5: MySipClient[19168], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=0DC3A1127E8E218D
2020.08.28 21:37:35 4: MySipClient[19168], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.08.28 21:37:35 4: MySipClient[19168], cb_create : INVITE
2020.08.28 21:37:35 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.08.28 21:37:35 5: MySipClient, readingB:caller_nr Val:**610
2020.08.28 21:37:35 5: MySipClient, readingB:caller_name Val:Buero
2020.08.28 21:37:35 5: MySipClient, readingB:caller_time Val:0
2020.08.28 21:37:35 5: MySipClient, readingB:caller_state Val:calling
2020.08.28 21:37:35 5: MySipClient[19168], cb_invite_dtmf
2020.08.28 21:37:35 5: MySipClient, readingS:caller_state Val:ringing
2020.08.28 21:37:38 5: MySipClient[19168], cb_est_dtmf
2020.08.28 21:37:38 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:37:38 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 377 ms
2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 113 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 82 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 85 ms
2020.08.28 21:37:51 5: MySipClient[19168], SIP_bye : HASH(0x4db5cb0)
2020.08.28 21:37:51 5: MySipClient, readingB:caller Val:none
2020.08.28 21:37:51 5: MySipClient, readingB:caller_state Val:hangup
2020.08.28 21:37:51 5: MySipClient, readingB:caller_time Val:13
2020.08.28 21:37:51 5: MySipClient, readingB:caller_nr Val:---
2020.08.28 21:37:51 5: MySipClient, readingB:caller_name Val:---
2020.08.28 21:37:51 5: MySipClient[19168], while dtmf_loop : dtmfloop : 0 , byebye : 1
2020.08.28 21:37:51 5: MySipClient[19168], aufgelegt
2020.08.28 21:37:51 5: MySipClient[19168], while dtmf_loop, okloopbye : 0 , byebye : 1
2020.08.28 21:37:51 5: MySipClient[19168], end while dtmf_loop, byebye : 1
2020.08.28 21:37:51 5: MySipClient[19168], while(1)
2020.08.28 21:37:57 5: MySipClient, listen process 19168 found
2020.08.28 21:38:11 5: MySipClient[19168], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=4CEAC09AA82E4A5F
2020.08.28 21:38:11 4: MySipClient[19168], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.08.28 21:38:11 4: MySipClient[19168], cb_create : INVITE
2020.08.28 21:38:11 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.08.28 21:38:11 5: MySipClient, readingB:caller_nr Val:**610
2020.08.28 21:38:11 5: MySipClient, readingB:caller_name Val:Buero
2020.08.28 21:38:11 5: MySipClient, readingB:caller_time Val:0
2020.08.28 21:38:11 5: MySipClient, readingB:caller_state Val:calling
2020.08.28 21:38:11 5: MySipClient[19168], cb_invite_dtmf
2020.08.28 21:38:11 5: MySipClient, readingS:caller_state Val:ringing
2020.08.28 21:38:14 5: MySipClient[19168], cb_est_dtmf
2020.08.28 21:38:14 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:38:14 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:38:14 5: MySipClient[19168], DTMF Event: * - 901 ms
2020.08.28 21:38:17 5: MySipClient[19168], DTMF Event: 6 - 331 ms
2020.08.28 21:38:17 5: MySipClient[19168], DTMF: 6 , Anz: 2
2020.08.28 21:38:17 5: MySipClient, readingS:dtmf_event Val:6
2020.08.28 21:38:17 5: MySipClient[19168], DTMF Event: 6 - 28 ms
2020.08.28 21:38:20 5: MySipClient[19168], DTMF Event: 6 - 28 ms
2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : dtmfloop : 1 , byebye : 0
2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : reinvite2
2020.08.28 21:38:29 4: MySipClient[19168], register new expire : 2020-08-28 21:43:29
2020.08.28 21:38:29 5: MySipClient, readingB:state Val:listen_dtmf
2020.08.28 21:38:29 5: MySipClient, readingB:listen_alive Val:19168
2020.08.28 21:38:29 5: MySipClient, readingB:expire Val:300

jump to the top



Und hier das List:


Internals:
   AC         /usr/bin/sox
   FUUID      5f3d5a11-f33f-7695-cdae-b046c5875ba9a078
   LPID       19168
   NAME       MySipClient
   NOTIFYDEV  mytext2speech
   NR         141
   NTFY_ORDER 50-MySipClient
   STATE      listen_dtmf
   TYPE       SIP
   VERSION    V1.92 / 21.03.2020
   READINGS:
     2020-08-21 21:47:33   call            done
     2020-08-21 21:47:33   call_attempt    0
     2020-08-21 21:47:33   call_state      declined
     2020-08-21 21:47:33   call_success    0
     2020-08-21 21:47:33   call_time       0
     2020-08-28 21:38:11   caller          Buero sip:**610@fritz.box
     2020-08-28 21:38:11   caller_name     Buero
     2020-08-28 21:38:11   caller_nr       **610
     2020-08-28 21:38:14   caller_state    established
     2020-08-28 21:38:11   caller_time     0
     2020-08-28 21:38:17   dtmf_event      6
     2020-08-28 21:43:29   expire          300
     2020-08-23 17:40:44   last_error      attr audio_converter not set
     2020-08-28 21:43:29   listen_alive    19168
     2020-08-28 21:43:29   state           listen_dtmf
   helper:
     LISTEN_PID:
       abortArg   
       abortFn   
       arg        MySipClient
       bc_pid     488
       finishFn   SIP_ListenDone
       fn         SIP_ListenStart
       pid        19168
       timeout   
Attributes:
   T2S_Device mytext2speech
   audio_converter sox
   disabled   0
   history_file ./log/MySipClient.sip
   history_size 0
   sip_audiofile_dtmf cache/de901947c29afa3f195b50e87289aa4c.mp3
   sip_audiofile_ok cache/ab7acf820e3b2e2fc76c4012a5c7a991.mp3
   sip_dtmf_loop once
   sip_dtmf_send audio
   sip_dtmf_size 1
   sip_elbc   yes
   sip_from   sip:fhemsip1@fritz.box
   sip_ip     192.168.178.33
   sip_listen dtmf
   sip_port   5060
   sip_registrar 192.168.178.1
   sip_ringtime 3
   sip_user   fhemsip1
   verbose    5


Interessant ist diesmal, dass die "6" tatsächlich meine letzte Eingabe war.

Was is eigentlich mit meinen Code-Tags. Passt das jetzt?

Danke und Gruß,
Kurt

Jamo

Ja, mit deinen code tags sieht es jetzt super aus und man kann deine Beiträge gut lesen. Danke!
Bullseye auf iNUC, Homematic + HMIP(UART/HMUSB), Debmatic, HUEBridge, Zigbee/Conbee III, FB7690, Alexa (fhem-lazy), Livetracking, LaCrosse JeeLink, LoRaWan / TTN / Chirpstack, Sonos, ESPresence

Wzut

@Kurt, ich drösel dein Log mal etwas auf :
2020.08.28 21:35:57 4: MySipClient[19168], using cache/de901947c29afa3f195b50e87289aa4c.alaw for audio_dtmf
2020.08.28 21:35:57 4: MySipClient[19168], using cache/ab7acf820e3b2e2fc76c4012a5c7a991.alaw for audio_ok

das wollte ich sehen, das Modul erkennt zwei gültige Audio Dateien : gut/ok

2020.08.28 21:36:21 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:28 5: MySipClient[19168], DTMF Event: 9 - 86 ms
2020.08.28 21:36:42 5: MySipClient[19168], SIP_bye : HASH(0x4c4bdd8)

mit reinvite1 sollte deine Eingabe Nachricht abgespielt worden sein, gut. Aber dann wurden innnerhalb von 21 Sekunden nur eine Taste erkannt und diese auch noch zu kurz.
und nicht der Client hat aufgelegt sondern du : schlecht

020.08.28 21:36:58 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:58 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:37:06 5: MySipClient[19168], DTMF Event: 8 - 28 ms
2020.08.28 21:37:13 5: MySipClient[19168], SIP_bye : HASH(0x4dc3028)

fast das gleiche beim nächsten Versuch, wieder kam das Ende von dir.

2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop : dtmfloop : 0 , byebye : 1
2020.08.28 21:37:13 5: MySipClient[19168], aufgelegt
2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop, okloopbye : 0 , byebye : 1

Hier hoffe ich ist dir nur ein Fehler bei copy & paste unterlaufen, (erste und dritte Zeile gleich) das steht so hoffentlich nicht im Log. Bitte mal prüfen
Aber beim nächsten Fehlversuch mit der gedrückten 7 findet sich das so nochmal.

2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : dtmfloop : 1 , byebye : 0
2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : reinvite2

das ist nun dein letzter Versuch bei dem die Taste 6 erfolgreich erkannt wurde und mit reinvite2 eigentlich die OK Ansage abgespielt wurde. Hast du die gehört ?
Leider endet dein Log hier, die intressanten Zeilen wären danach gekommen bis zum while(1) .
Wenn die Datei noch vorhanden ist liefere bitte den Rest nach. Bzw nun wäre es wirklich intressant geworden mit einem weiteren Anruf.

Fazit : wurden die Fehlversuche wirklich von dir beendet obwohl ich extra geschrieben hatte das sollst du nicht tun ?
und gibt es im Log nach dem Ende deines Abschnittes einen weiteren Versuch ohne Reset ?


Maintainer der Module: MAX, MPD, UbiquitiMP, UbiquitiOut, SIP, BEOK, readingsWatcher

Kurt77

Zitat von: Wzut am 29 August 2020, 08:01:15
@Kurt, ich drösel dein Log mal etwas auf :
2020.08.28 21:35:57 4: MySipClient[19168], using cache/de901947c29afa3f195b50e87289aa4c.alaw for audio_dtmf
2020.08.28 21:35:57 4: MySipClient[19168], using cache/ab7acf820e3b2e2fc76c4012a5c7a991.alaw for audio_ok

das wollte ich sehen, das Modul erkennt zwei gültige Audio Dateien : gut/ok

2020.08.28 21:36:21 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:28 5: MySipClient[19168], DTMF Event: 9 - 86 ms
2020.08.28 21:36:42 5: MySipClient[19168], SIP_bye : HASH(0x4c4bdd8)

mit reinvite1 sollte deine Eingabe Nachricht abgespielt worden sein, gut. Aber dann wurden innnerhalb von 21 Sekunden nur eine Taste erkannt und diese auch noch zu kurz.
und nicht der Client hat aufgelegt sondern du : schlecht

020.08.28 21:36:58 5: MySipClient[19168], while dtmf_loop : start reinvite1
2020.08.28 21:36:58 5: MySipClient, readingS:caller_state Val:established
2020.08.28 21:37:06 5: MySipClient[19168], DTMF Event: 8 - 28 ms
2020.08.28 21:37:13 5: MySipClient[19168], SIP_bye : HASH(0x4dc3028)

fast das gleiche beim nächsten Versuch, wieder kam das Ende von dir.

2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop : dtmfloop : 0 , byebye : 1
2020.08.28 21:37:13 5: MySipClient[19168], aufgelegt
2020.08.28 21:37:13 5: MySipClient[19168], while dtmf_loop, okloopbye : 0 , byebye : 1

Hier hoffe ich ist dir nur ein Fehler bei copy & paste unterlaufen, (erste und dritte Zeile gleich) das steht so hoffentlich nicht im Log. Bitte mal prüfen
Aber beim nächsten Fehlversuch mit der gedrückten 7 findet sich das so nochmal.

2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : dtmfloop : 1 , byebye : 0
2020.08.28 21:38:29 5: MySipClient[19168], while dtmf_loop : reinvite2

das ist nun dein letzter Versuch bei dem die Taste 6 erfolgreich erkannt wurde und mit reinvite2 eigentlich die OK Ansage abgespielt wurde. Hast du die gehört ?
Leider endet dein Log hier, die intressanten Zeilen wären danach gekommen bis zum while(1) .
Wenn die Datei noch vorhanden ist liefere bitte den Rest nach. Bzw nun wäre es wirklich intressant geworden mit einem weiteren Anruf.

Fazit : wurden die Fehlversuche wirklich von dir beendet obwohl ich extra geschrieben hatte das sollst du nicht tun ?
und gibt es im Log nach dem Ende deines Abschnittes einen weiteren Versuch ohne Reset ?
Hallo Wzut,
ja, ich habe aufgelegt, weil nicht automatisch aufgelegt wird und zwar in keienem Fall! Es gibt nach dem kopierten Abschnitt keinen weiteren Anruf ohne reset.
Kopier fehler sind auch auszuschließen, weil ich den gesamten Code in einem Rutsch in den Forenbeitrag kopiert habe.
Die abschließende Audiodatei hab ich nicht gehört.
Es feht kein Code. - Ich habe alles geliefert, was im Log gezeigt wurde.

Gruß Kurt

plin

#1040
Zitat von: Kurt77 am 29 August 2020, 11:54:11
ja, ich habe aufgelegt, weil nicht automatisch aufgelegt wird und zwar in keienem Fall! Es gibt nach dem kopierten Abschnitt keinen weiteren Anruf ohne reset.

Hallo Kurt,

Du musst in dem Fall einfach weiter Tasten betätigen. Und zwar so lange, bis zwei davon lang genug gedrückt wurden, um einen 2stelliger Code zu erkennen. Dann sollte der SIP-Client auch auflegen. Spiel ruhig 5 Anrufe auf diese Art durch, damit wir etwas Futter haben wie das Timing aussieht.

Events wie

2020.08.28 21:36:28 5: MySipClient[19168], DTMF Event: 9 - 86 ms
2020.08.28 21:37:06 5: MySipClient[19168], DTMF Event: 8 - 28 ms
2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 377 ms
2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 113 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 82 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 85 ms
2020.08.28 21:38:14 5: MySipClient[19168], DTMF Event: * - 901 ms
2020.08.28 21:38:17 5: MySipClient[19168], DTMF Event: 6 - 28 ms
2020.08.28 21:38:20 5: MySipClient[19168], DTMF Event: 6 - 28 ms

sind zu kurz (valide Tastenanschläge beginnen bei 90 ms), um als valide durchzugehen. Die werden dann unterdrückt.

VG plin
FHEM1 (Main) Raspi4 mit CUL, Homematic, SDUINO 433/OOK, zentrale Steuerung
FHEM2 (Keller) x86 mit CUL/hmland, IP-basierte Module
FHEM3 (Erdgeschoss) Raspi2 mit SDUINO 868/GFSK
FHEM4 (Hausanschlussraum), USV und OBIS-Modul
FHEM5 (Docker) mit FHEM2FHEM, InfluxDB

Wzut

Zur Info , da wir ja das Thema Net:SIP update via CPAN erst die Tage hatten :
Ich habe meine Testsystem hochgezogen von 0.808 auf die aktuelle 0.823
Wichtig : Debian/Raspian installiert Net/SIP in einem anderem Verzeichniss als CPAN !
CPAN : /usr/local/share/perl/5.24.1/Net/  ( bei mir , ich bin noch 5.24 unter Stretch unterwegs ) 
Debian : /usr/share/perl5/Net/

Da ich inzwischen die Net:SIP Version im Modul anzeige sah man auch schön das sich nach dem Update an der Anzeige 0.808 nichts geändert hat.
Ich habe dann das   /usr/share/perl5/Net/  in  /usr/share/perl5/Net_old/ umbennant und /usr/local/share/perl/5.24.1/Net/ dann als /usr/share/perl5/Net/ rüber kopiert.
Und nach einem (zur Sicherheit) reboot hatte ich dann auch meine aktuelle Anzeige im Modul.
Maintainer der Module: MAX, MPD, UbiquitiMP, UbiquitiOut, SIP, BEOK, readingsWatcher

frank

hatte cpan seinen pfad nicht in INC eingetragen?
oder ist die position des pfades ungünstig?

ich habe bei mir als letztes ein upgrade auf buster gemacht. dabei wurde INC scheinbar ordentlich aufgeräumt.
somit ist zur zeit die buster version 820 aktiv.

ich schaue demnächst vielleicht mal, was bei mir passiert, wenn ich nun noch mal cpan aktiviere.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Kurt77

Zitat von: plin am 30 August 2020, 09:59:44
Hallo Kurt,

Du musst in dem Fall einfach weiter Tasten betätigen. Und zwar so lange, bis zwei davon lang genug gedrückt wurden, um einen 2stelliger Code zu erkennen. Dann sollte der SIP-Client auch auflegen. Spiel ruhig 5 Anrufe auf diese Art durch, damit wir etwas Futter haben wie das Timing aussieht.

Events wie

2020.08.28 21:36:28 5: MySipClient[19168], DTMF Event: 9 - 86 ms

2020.08.28 21:37:06 5: MySipClient[19168], DTMF Event: 8 - 28 ms
2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 377 ms

2020.08.28 21:37:39 5: MySipClient[19168], DTMF Event: # - 113 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 82 ms
2020.08.28 21:37:42 5: MySipClient[19168], DTMF Event: 7 - 85 ms
2020.08.28 21:38:14 5: MySipClient[19168], DTMF Event: * - 901 ms
2020.08.28 21:38:17 5: MySipClient[19168], DTMF Event: 6 - 28 ms
2020.08.28 21:38:20 5: MySipClient[19168], DTMF Event: 6 - 28 ms

sind zu kurz (valide Tastenanschläge beginnen bei 90 ms), um als valide durchzugehen. Die werden dann unterdrückt.

VG plin
Hallo plin,
habe gerade eben 5 Minuten lang nach einem Anruf (**621) auf der Telefontastatur rumgedrückt. Es wird kein dtmf-Code erkannt und es wird auch nicht aufgelegt.
Was tun?

Gruß Kurt

Wzut

ja das ist für dich vermutlich etwas schwierig , ich lass dabei den Event Monitor laufen und setze den Log Haken,
oder ich mach ein extra Fenster auf in dem ein tail -f aufs Log läuft und dann "sieht" man halt sofort was fehlt.
Anyway, ich kann heute Abend meine aktuelle Beta Version hier einstellen mit dem neuen Attribut um die 90ms kleiner zu machen.
Maintainer der Module: MAX, MPD, UbiquitiMP, UbiquitiOut, SIP, BEOK, readingsWatcher

Kurt77

Hallo Wzut,
also habe ich jetzt auch mal den event-monitor aufgemacht. Das Problem ist eben gerade, dass nichts passiert.

Code:
------------------------------------------
20.08.31 11:17:03 5 : MySipClient, listen process 19168 foun
-----------------------------------------

Diese Zeile steht da und wird auch bei Tastendruck nicht aktualisiert. Das einzige ist, dass diese Zeile in regelmäßigen abständen erneut auf dem Bildschirm erscheint.

Gruß Kurt

Wzut

Das ganze Thema Logging ist bei dir offensichtlich schon sehr merkwürdig, normal sieht man direkt wenn man den Client anwählt und dieser den Ruf annimmt.
Ich vermute fast im Moment nimmt der gar keine Rufe an und in deiner Fritte unter System Ereignisse Telefon hast du Meldungen mit Code 408 ?
Maintainer der Module: MAX, MPD, UbiquitiMP, UbiquitiOut, SIP, BEOK, readingsWatcher

Kurt77

Hallo Wzut,
keine Fehlermeldungen im Ereignisprotokoll. Anrufe mit call kommen durch.

Gruß Kurt

plin

Zitat von: Kurt77 am 31 August 2020, 11:49:04
keine Fehlermeldungen im Ereignisprotokoll. Anrufe mit call kommen durch.

Hast Du mal geschaut was im fhem-2020-08.log passiert?
FHEM1 (Main) Raspi4 mit CUL, Homematic, SDUINO 433/OOK, zentrale Steuerung
FHEM2 (Keller) x86 mit CUL/hmland, IP-basierte Module
FHEM3 (Erdgeschoss) Raspi2 mit SDUINO 868/GFSK
FHEM4 (Hausanschlussraum), USV und OBIS-Modul
FHEM5 (Docker) mit FHEM2FHEM, InfluxDB

plin

#1049
@Wzut: ich habe nach DTNF und Dauer geggoelt und einen Wikipedia-Eintrag gefunden: https://de.wikipedia.org/wiki/Mehrfrequenzwahlverfahren

Demnach wird bei < 23 ms die Funktion verweigert, bei > 40 ms Signaldauer soll es eine gültige Funktion sein. Da nähern wir uns den Zeiten die das FritzFON M erzeugt.

Da steht aber auch "Für die Dauer eines Tones wird meist (wie bei ZVEI-Tönen) 70 Millisekunden gewählt, damit die Vermittlungseinrichtung den empfangenen Ton sicher erkennen kann. Empfohlen wird eine Dauer von 50–100 ms mit Pausen von 20–50 ms zwischen den Tönen bei Ton- beziehungsweise Ziffernfolgen."

Bei meinem FritzFON C5 sieht es so aus (die Tasten der Reihe nach nur einmal betätigt):


< ohne Eingabe von #>
2020.08.31 13:18:49 5: SipTest[1639], DTMF Event: 1 - 344 ms
2020.08.31 13:18:49 5: SipTest[1639], DTMF Event: 1 - 39 ms
2020.08.31 13:18:51 5: SipTest[1639], DTMF Event: 2 - 744 ms
2020.08.31 13:18:51 5: SipTest[1639], DTMF Event: 2 - 59 ms
2020.08.31 13:18:54 5: SipTest[1639], DTMF Event: 3 - 124 ms
2020.08.31 13:18:54 5: SipTest[1639], DTMF Event: 3 - 60 ms
2020.08.31 13:18:55 5: SipTest[1639], DTMF Event: 4 - 964 ms
2020.08.31 13:18:57 5: SipTest[1639], DTMF Event: 5 - 544 ms
2020.08.31 13:18:59 5: SipTest[1639], DTMF Event: 6 - 244 ms
2020.08.31 13:19:01 5: SipTest[1639], DTMF Event: 7 - 45 ms
2020.08.31 13:19:01 5: SipTest[1639], DTMF Event: 7 - 40 ms
2020.08.31 13:19:02 5: SipTest[1639], DTMF Event: 8 - 524 ms
2020.08.31 13:19:02 5: SipTest[1639], DTMF Event: 8 - 60 ms
2020.08.31 13:19:04 5: SipTest[1639], DTMF Event: 9 - 245 ms
2020.08.31 13:19:04 5: SipTest[1639], DTMF Event: 9 - 60 ms
2020.08.31 13:19:05 5: SipTest[1639], DTMF Event: 0 - 963 ms
2020.08.31 13:19:06 5: SipTest[1639], DTMF Event: 0 - 22 ms
2020.08.31 13:19:07 5: SipTest[1639], DTMF Event: 1 - 684 ms
2020.08.31 13:19:07 5: SipTest[1639], DTMF Event: 1 - 79 ms
2020.08.31 13:19:08 5: SipTest[1639], DTMF Event: 1 - 100 ms
2020.08.31 13:19:09 5: SipTest[1639], DTMF Event: 2 - 184 ms
2020.08.31 13:19:09 5: SipTest[1639], DTMF Event: 2 - 79 ms
2020.08.31 13:19:10 5: SipTest[1639], DTMF Event: 3 - 732 ms
2020.08.31 13:19:10 5: SipTest[1639], DTMF Event: 3 - 34 ms
2020.08.31 13:19:12 5: SipTest[1639], DTMF Event: 4 - 164 ms
2020.08.31 13:19:13 5: SipTest[1639], DTMF Event: 5 - 564 ms
2020.08.31 13:19:14 5: SipTest[1639], DTMF Event: 6 - 964 ms
2020.08.31 13:19:16 5: SipTest[1639], DTMF Event: 7 - 384 ms
2020.08.31 13:19:16 5: SipTest[1639], DTMF Event: 7 - 60 ms
2020.08.31 13:19:17 5: SipTest[1639], DTMF Event: 8 - 624 ms
2020.08.31 13:19:18 5: SipTest[1639], DTMF Event: 9 - 984 ms
2020.08.31 13:19:20 5: SipTest[1639], DTMF Event: 0 - 224 ms
2020.08.31 13:19:21 5: SipTest[1639], DTMF Event: # - 544 ms
2020.08.31 13:19:22 5: SipTest[1639], DTMF Event: 1 - 704 ms
< hier wurde jetzt ein # eingegeben>
2020.08.31 13:19:22 5: SipTest[1639], DTMF: 1 , Anz: 2
2020.08.31 13:19:22 5: SipTest[1639], DTMF Event: 1 - 59 ms
2020.08.31 13:19:23 5: SipTest[1639], DTMF Event: 1 - 59 ms
2020.08.31 13:19:23 5: SipTest[1639], DTMF Event: 2 - 684 ms
2020.08.31 13:19:23 5: SipTest[1639], DTMF: 12 , Anz: 3


Da sind auch einige recht lange Tastenpreller mit dabei (z.B. das DTMF Event: 1 - 39 ms).

Die Auslagerung des Schwellwertes in ein Attribut erscheint mir sinnvoll. Wenn die Grenze von 40 ms der Spezifikation entspricht, könnte man den Wert als Default nehmen.
FHEM1 (Main) Raspi4 mit CUL, Homematic, SDUINO 433/OOK, zentrale Steuerung
FHEM2 (Keller) x86 mit CUL/hmland, IP-basierte Module
FHEM3 (Erdgeschoss) Raspi2 mit SDUINO 868/GFSK
FHEM4 (Hausanschlussraum), USV und OBIS-Modul
FHEM5 (Docker) mit FHEM2FHEM, InfluxDB