Modul 96_SIP

Wzut · 31 August 2020, 14:19:57

kein Problem, ich habe z.B. ganz am Anfang einen erkannten Stern mit 9ms ohne das ich überhaupt ne Taste gedrückt habe ....

frank · 31 August 2020, 15:07:58

@Kurt

in der fritzbox unter "Telefonie > Telefoniegeräte > edit beim jeweiligen fon > Merkmale des Telefoniegerätes"
gibt es eine reihe von einstellungen, die den "klang" beeinflussen können.

hast du hier eventuell etwas "optimiert"?

ganz unten hinter störfilter gibt es dort bei mir noch folgenden hinweis:

"Beachten Sie bitte, dass ein aktivierter USB 3.0 Anschluss eine Störung der DECT-Übertragung zur Folge haben kann."

trifft das eventuell zu?

Kurt77 · 31 August 2020, 16:27:32

Zitat von: frank am 31 August 2020, 15:07:58
@Kurt

in der fritzbox unter "Telefonie > Telefoniegeräte > edit beim jeweiligen fon > Merkmale des Telefoniegerätes"
gibt es eine reihe von einstellungen, die den "klang" beeinflussen können.

hast du hier eventuell etwas "optimiert"?

ganz unten hinter störfilter gibt es dort bei mir noch folgenden hinweis:

"Beachten Sie bitte, dass ein aktivierter USB 3.0 Anschluss eine Störung der DECT-Übertragung zur Folge haben kann."

trifft das eventuell zu?

Hallo Frank,
"Merkmale des Telefoniegerätes" gibt es hier nicht.
Ich sehe nur die Reiter "Anmeldedaten" und "IP-Telefon".

Gruß Kurt

@plin: Was soll ich mir denn in der "fhem-2020-08.log genau angucken? Ich sehe keine Auffälligkeiten.

plin · 31 August 2020, 16:57:41

Zitat von: Kurt77 am 31 August 2020, 16:27:32
@plin: Was soll ich mir denn in der "fhem-2020-08.log genau angucken? Ich sehe keine Auffälligkeiten.

Du schreibst Du hättest 5 Minuten lang diverse Tasten betätigt und keine Events gesehen und der SIpClient hat auch nicht aufgelegt. Ich würde erwarten zumindest Log-Einträge vom Typ

Code Auswählen

2020.08.31 13:18:49 5: SipTest[1639], DTMF Event: 1 - 34 ms
zu sehen, bei denen die Zeiten alle kleiner als 90 ms sind.

Wzut · 31 August 2020, 18:01:20

Zitat von: Kurt77 am 31 August 2020, 16:27:32
Ich sehe nur die Reiter "Anmeldedaten" und "IP-Telefon".

Du bist am falschen Gerät , so sparsam schaut das bei LAN/WLAN Telefonen aus.
Du sollst aber dein DECT Telefon auswählen mit dem du immer testest, da hast ein paar Reiter mehr.

Kurt77 · 01 September 2020, 11:10:43

Zitat von: plin am 31 August 2020, 16:57:41
Du schreibst Du hättest 5 Minuten lang diverse Tasten betätigt und keine Events gesehen und der SIpClient hat auch nicht aufgelegt. Ich würde erwarten zumindest Log-Einträge vom Typ
Code Auswählen Erweitern
2020.08.31 13:18:49 5: SipTest[1639], DTMF Event: 1 - 34 ms
zu sehen, bei denen die Zeiten alle kleiner als 90 ms sind.

Hallo plin,
ich habe jetzt nochmal 3 Telefongespräche durchgeführt und habe jeweils aufgelegt, weil nicht automatisch aufgelegt wurde.

Hier ist meine fhem-2020-09.log

Code Auswählen


2020.09.01 10:54:21 5: MySipClient[18029], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=A014DDB29AAA6CE5
2020.09.01 10:54:21 4: MySipClient[18029], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.09.01 10:54:21 4: MySipClient[18029], cb_create : INVITE
2020.09.01 10:54:21 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.09.01 10:54:21 5: MySipClient, readingB:caller_nr Val:**610
2020.09.01 10:54:21 5: MySipClient, readingB:caller_name Val:Buero
2020.09.01 10:54:21 5: MySipClient, readingB:caller_time Val:0
2020.09.01 10:54:21 5: MySipClient, readingB:caller_state Val:calling
2020.09.01 10:54:21 5: MySipClient[18029], cb_invite_dtmf
2020.09.01 10:54:21 5: MySipClient, readingS:caller_state Val:ringing
2020.09.01 10:54:24 5: MySipClient[18029], cb_est_dtmf
2020.09.01 10:54:24 5: MySipClient, readingS:caller_state Val:established
2020.09.01 10:54:25 4: MySipClient[18029], register new expire : 2020-09-01 10:59:25
2020.09.01 10:54:25 5: MySipClient, readingB:state Val:listen_dtmf
2020.09.01 10:54:25 5: MySipClient, readingB:listen_alive Val:18029
2020.09.01 10:54:25 5: MySipClient, readingB:expire Val:300
2020.09.01 10:54:35 5: MySipClient, listen process 18029 found
2020.09.01 10:54:37 5: MySipClient[18029], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=B80E4B7ADE072BA6
2020.09.01 10:54:37 4: MySipClient[18029], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.09.01 10:54:37 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.09.01 10:54:37 5: MySipClient, readingB:caller_nr Val:**610
2020.09.01 10:54:37 5: MySipClient, readingB:caller_name Val:Buero
2020.09.01 10:54:37 5: MySipClient, readingB:caller_time Val:0
2020.09.01 10:54:37 5: MySipClient, readingB:caller_state Val:calling
2020.09.01 10:54:37 4: MySipClient[18029], cb_create : INVITE
2020.09.01 10:54:37 5: MySipClient[18029], cb_invite_dtmf
2020.09.01 10:54:37 5: MySipClient, readingS:caller_state Val:ringing
2020.09.01 10:54:40 5: MySipClient[18029], cb_est_dtmf
2020.09.01 10:54:40 5: MySipClient, readingS:caller_state Val:established
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_3 Get called. Relay state: 0, RSSI: -53
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_3 Updating readings
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_3 Get end
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_2 Get called. Relay state: 1, RSSI: -61
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_2 Updating readings
2020.09.01 10:54:50 3: TPLinkHS110: dWohnzimmerstecker_2 Get end
2020.09.01 10:54:51 3: TPLinkHS110: dWohnzimmerstecker_1 Get called. Relay state: 0, RSSI: -58
2020.09.01 10:54:51 3: TPLinkHS110: dWohnzimmerstecker_1 Updating readings
2020.09.01 10:54:51 3: TPLinkHS110: dWohnzimmerstecker_1 Get end
2020.09.01 10:55:35 5: MySipClient, listen process 18029 found
2020.09.01 10:56:11 5: MySipClient[18029], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=D23E4588DB24BEEC
2020.09.01 10:56:11 4: MySipClient[18029], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.09.01 10:56:11 4: MySipClient[18029], cb_create : INVITE
2020.09.01 10:56:11 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.09.01 10:56:11 5: MySipClient, readingB:caller_nr Val:**610
2020.09.01 10:56:11 5: MySipClient, readingB:caller_name Val:Buero
2020.09.01 10:56:11 5: MySipClient, readingB:caller_time Val:0
2020.09.01 10:56:11 5: MySipClient, readingB:caller_state Val:calling
2020.09.01 10:56:11 5: MySipClient[18029], cb_invite_dtmf
2020.09.01 10:56:11 5: MySipClient, readingS:caller_state Val:ringing
2020.09.01 10:56:14 5: MySipClient[18029], cb_est_dtmf
2020.09.01 10:56:14 5: MySipClient, readingS:caller_state Val:established
2020.09.01 10:56:35 5: MySipClient, listen process 18029 found
2020.09.01 10:56:38 3: d_netatmo_wohnzimmer: poll (DEVICE)
2020.09.01 10:56:38 3: d_netatmo_wohnzimmer: requestDeviceReadings (Temperature,CO2,Humidity,Noise,Pressure)
2020.09.01 10:56:38 3: d_netatmo_wohnzimmer: next dynamic update (Temperature,CO2,Humidity,Noise,Pressure) at 2020-09-01 11:06:55
2020.09.01 10:56:55 4: MySipClient[18029], register new expire : 2020-09-01 11:01:55
2020.09.01 10:56:55 5: MySipClient, readingB:state Val:listen_dtmf
2020.09.01 10:56:55 5: MySipClient, readingB:listen_alive Val:18029
2020.09.01 10:56:55 5: MySipClient, readingB:expire Val:300
2020.09.01 10:57:24 5: MySipClient[18029], SIP_filter : "Buero" <sip:**610@fritz.box>;tag=B889FD7FD986B631
2020.09.01 10:57:24 4: MySipClient[18029], SIP_filter: caller Buero sip:**610@fritz.box, caller_nr **610, caller_name Buero
2020.09.01 10:57:24 5: MySipClient, readingB:caller Val:Buero sip:**610@fritz.box
2020.09.01 10:57:24 5: MySipClient, readingB:caller_nr Val:**610
2020.09.01 10:57:24 5: MySipClient, readingB:caller_name Val:Buero
2020.09.01 10:57:24 5: MySipClient, readingB:caller_time Val:0
2020.09.01 10:57:24 5: MySipClient, readingB:caller_state Val:calling
2020.09.01 10:57:24 4: MySipClient[18029], cb_create : INVITE
2020.09.01 10:57:24 5: MySipClient[18029], cb_invite_dtmf
2020.09.01 10:57:24 5: MySipClient, readingS:caller_state Val:ringing
2020.09.01 10:57:27 5: MySipClient[18029], cb_est_dtmf
2020.09.01 10:57:27 5: MySipClient, readingS:caller_state Val:established
2020.09.01 10:57:35 5: MySipClient, listen process 18029 found
2020.09.01 10:58:35 5: MySipClient, listen process 18029 found
2020.09.01 10:59:25 4: MySipClient[18029], register new expire : 2020-09-01 11:04:25
2020.09.01 10:59:25 5: MySipClient, readingB:state Val:listen_dtmf
2020.09.01 10:59:25 5: MySipClient, readingB:listen_alive Val:18029
2020.09.01 10:59:25 5: MySipClient, readingB:expire Val:300
2020.09.01 10:59:35 5: MySipClient, listen process 18029 found
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_3 Get called. Relay state: 0, RSSI: -56
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_3 Updating readings
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_3 Get end
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_2 Get called. Relay state: 1, RSSI: -63
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_2 Updating readings
2020.09.01 10:59:50 3: TPLinkHS110: dWohnzimmerstecker_2 Get end
2020.09.01 10:59:51 3: TPLinkHS110: dWohnzimmerstecker_1 Get called. Relay state: 0, RSSI: -60
2020.09.01 10:59:51 3: TPLinkHS110: dWohnzimmerstecker_1 Updating readings
2020.09.01 10:59:51 3: TPLinkHS110: dWohnzimmerstecker_1 Get end
2020.09.01 11:00:36 5: MySipClient, listen process 18029 found
2020.09.01 11:01:36 5: MySipClient, listen process 18029 found
2020.09.01 11:01:55 4: MySipClient[18029], register new expire : 2020-09-01 11:06:55
2020.09.01 11:01:55 5: MySipClient, readingB:state Val:listen_dtmf
2020.09.01 11:01:55 5: MySipClient, readingB:listen_alive Val:18029
2020.09.01 11:01:55 5: MySipClient, readingB:expire Val:300
2020.09.01 11:02:36 5: MySipClient, listen process 18029 found

Danke und Gruß,
Kurt

plin · 01 September 2020, 11:49:24

@Kurt: Was ist denn mit den DTMF Events? Steht das Attribut verbose immer noch auf 5?

Kurt77 · 01 September 2020, 12:30:17

Hallo plin,
hier ist das list.
Gruß Kurt

Code Auswählen


Internals:
   AC         /usr/bin/sox
   FUUID      5f3d5a11-f33f-7695-cdae-b046c5875ba9a078
   LPID       18029
   NAME       MySipClient
   NOTIFYDEV  mytext2speech
   NR         141
   NTFY_ORDER 50-MySipClient
   STATE      listen_dtmf
   TYPE       SIP
   VERSION    V1.92 / 21.03.2020
   READINGS:
     2020-08-31 11:46:38   call            done
     2020-08-31 11:46:38   call_attempt    0
     2020-08-31 11:46:38   call_state      declined
     2020-08-31 11:46:38   call_success    0
     2020-08-31 11:46:38   call_time       0
     2020-09-01 10:57:24   caller          Buero sip:**610@fritz.box
     2020-09-01 10:57:24   caller_name     Buero
     2020-09-01 10:57:24   caller_nr       **610
     2020-09-01 10:57:27   caller_state    established
     2020-09-01 10:57:24   caller_time     0
     2020-08-31 17:09:38   dtmf_event      5
     2020-09-01 12:26:56   expire          300
     2020-08-23 17:40:44   last_error      attr audio_converter not set
     2020-09-01 12:26:56   listen_alive    18029
     2020-09-01 12:26:56   state           listen_dtmf
   helper:
     CALL_BYE   declined
     CALL_ERROR 1
     CALL_NAME  unknown
     CALL_NR    **611
     CALL_START 1598867170
     CALL_TIME  0
     CALL_TYPE  out
     LISTEN_PID:
       abortArg   
       abortFn    
       arg        MySipClient
       bc_pid     1363
       finishFn   SIP_ListenDone
       fn         SIP_ListenStart
       pid        18029
       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

Wzut · 01 September 2020, 12:55:15

Ich wiederhole mich ja nur ungern, aber IMHO wird das so nichts -> 2020.09.01 10:57:27 5: MySipClient[18029], cb_est_dtmf
nie kommt da der nächste Schritt mit reinvite1 und das war vor Tagen schon mal anderes.
Aber wohl seit dem Net::SIP Update läuft da was gründlich schief.

plin · 01 September 2020, 12:59:00

@Wzut: bedeutet neben der Net::SIP-Version sollten wir vielleicht auch den perl-Library path unter INTERNALS mit ausweisen?

Code Auswählen

perl -e "print qq(@INC)"

Wzut · 01 September 2020, 13:17:25

Na das kann er ja mal auf der Konsole eingeben.
Hier ersteinmal wie versprochen meine aktuelle Beta, die dtmf Erkennung ist jetzt runter auf 45ms und das Logging etwas angepasst.
Da ich wegen PBP und perlcritic extrem viel geändert habe darf ruhig auch derjenige mit testen der z.Z. keine Probleme hat, denn ich habe bis jetzt nicht alle Varianten die das Modul z.Z. bietet durch.

plin · 01 September 2020, 13:37:30

neue Beta-Version:

listen_DTMF klappt auch wenn ich über die Tasten husche

Der Ansagetext für sip_audiofile_wfp wird nicht korrekt aufbereitet. Ich habe "!Willkommen" eingetragen. Raus kommt ein Löwe der langsam "Danke" sagt. Den Text mittels T2S generiert und das Audiofile hinterlegt. Klappt, es ist "Danke" zu hören. Text auf "!Herzlich Willkommen" geändert. Es ist immer noch "Willkommen" (wie im mp3-File) zu hören.

Code Auswählen

2020.09.01 13:38:20 5: SipTest , SIP_Attr : set, sip_audiofile_wfp, !Sehr herzlich Willkommen
2020.09.01 13:38:20 5: SipTest , SIP_Attr : reset
2020.09.01 13:38:20 5: SipTest, updateConfig
2020.09.01 13:38:20 4: SipTest, Listen Kill PID : 4289
2020.09.01 13:38:20 4: SipTest, Reset Listen done
2020.09.01 13:38:20 5: SipTest, MD5: Herzlich Willkommen -> b94ca62a54098120fbc64e462c22cc7c.mp3
2020.09.01 13:38:20 5: SipTest, mp3 File file not found in cache
2020.09.01 13:38:20 4: SipTest, hole Herzlich Willkommen
2020.09.01 13:38:20 5: SipTest, Notify T2S , playing: 1
2020.09.01 13:38:20 5: SipTest, Notify T2S , duration: 2
2020.09.01 13:38:21 5: SipTest, Notify T2S , endTime: 00:00:00
2020.09.01 13:38:23 5: SipTest, Notify T2S , lastFilename: cache/b94ca62a54098120fbc64e462c22cc7c.mp3
2020.09.01 13:38:23 4: SipTest, wait_for_t2s file : cache/b94ca62a54098120fbc64e462c22cc7c.mp3
2020.09.01 13:38:23 4: SipTest, new T2S file cache/b94ca62a54098120fbc64e462c22cc7c.mp3
2020.09.01 13:38:23 5: SipTest, /usr/bin/sox cache/b94ca62a54098120fbc64e462c22cc7c.mp3 -t raw -r 8000 -c 1 -e a-law cache/b94ca62a54098120fbc64e462c22cc7c.alaw 2>&1
2020.09.01 13:38:24 4: SipTest, Listen new PID : 4577
2020.09.01 13:38:24 4: SipTest[4577], my parent is 3780
2020.09.01 13:38:24 4: SipTest[4577], trying to use port 5070
2020.09.01 13:38:24 5: SipTest, Notify T2S , playing: 0
2020.09.01 13:38:24 4: SipTest[4577], register new expire : 2020-09-01 13:43:24
2020.09.01 13:38:24 5: SipTest, readingB:state Val:listen_wfp
2020.09.01 13:38:24 5: SipTest, readingB:listen_alive Val:4577
2020.09.01 13:38:24 5: SipTest, readingB:expire Val:300
2020.09.01 13:38:24 3: SipTest[4577], Text : !Eine zweistellige Zahl bitte found, ignoring it
2020.09.01 13:38:24 3: SipTest[4577], Text : !Danke found, ignoring it
2020.09.01 13:38:24 5: SipTest[4577], audio file cache/b94ca62a54098120fbc64e462c22cc7c.alaw found
2020.09.01 13:38:24 4: SipTest[4577], using cache/b94ca62a54098120fbc64e462c22cc7c.alaw for audio_wfp

Kurt77 · 01 September 2020, 15:42:13

Zitat von: Wzut am 01 September 2020, 13:17:25
Na das kann er ja mal auf der Konsole eingeben.

Hat er eingegeben.

Code Auswählen


/etc/perl /usr/local/lib/arm-linux-gnueabihf/perl/5.20.2 /usr/local/share/perl/5.20.2 /usr/lib/arm-linux-gnueabihf/perl5/5.20 /usr/share/perl5 /usr/lib/arm-linux-gnueabihf/perl/5.20 /usr/share/perl/5.20 /usr/local/lib/site_perl .

Gruß Kurt

plin · 01 September 2020, 15:58:16

Dann könnte man jetzt noch ein

Code Auswählen

find /usr/ -name "SIP.pm"
hinterherschieben, um zu sehen, ob es das File mehrfach gibt.

Kurt77 · 01 September 2020, 16:10:33

Hallo plin,
das ist das Ergebnis von "find...":

Code Auswählen


/usr/share/perl5/Net/SIP.pm
/usr/local/share/perl/5.20.2/Net/SIP.pm

Und es gibt die Datei sogar noch ein drittes Mal unter /opt/fhem/FHEM.
Dahin habe ich gerade Wzut's Testversion hinkopiert. Gehört sie da nicht hin?

Danke und Gruß,
Kurt