Geisteranrufe

  • Hallo.


    Ein Kollege war schon öfters bei mir und beklagte "Geisteranrufe". Also Anrufe, die sein System (UC-Client / S650H Pro) selbst ausführen. Heute ist dieses Phänomen auch zweimal wieder aufgetreten und diesmal habe ich es a) "sehen" können und b) das Log dazu. Sieht für mich aber erstmal unverdächtig aus.


    2017-05-29 13_21_25-192.168.1.50 _ STARFACE VoIP Software.jpg


    2017-05-29 13_26_52-Clipboard.jpg


    Meine Fragen nun: Hat jemand ähnliche "Erfahrungen"? Muss ich Geisterjäger beauftragen? Übersehe ich irgend etwas?


    Aktuell ist bei uns wirklich der "Wurm" drin. Siehe auch meine anderen Threads.


    Danke für Feedback.


    Hier nochmal das Log:


    [2017-05-29 13:17:57,127] [0989] ********* Call created *********
    [2017-05-29 13:17:58,251] [0989] SIP/1329.N720-0000094b Channelstate is Ringing
    [2017-05-29 13:17:58,897] [0989] SIP/1329.N72
    0-0000094b Channelstate is Up
    [2017-05-29 13:17:58,936] [0989] Routing call "click2dial: +4925645686137 <+4925645686137>" to number +4925645686137 over service OutgoingService
    [2017-05-29 13:17:58,936] [0989] CallLeg a4dc71da-1dca-4a6a-a653-f6774ef08175
    [2017-05-29 13:17:58,936] [0989] Found cor/lbr rules: Alle
    [2017-05-29 13:17:58,936] [0989] Found lines for LINE_COR routing
    [2017-05-29 13:17:58,936] [0989] - SIP/90785253273
    [2017-05-29 13:17:58,936] [0989] - SIP/90785253273
    [2017-05-29 13:17:58,937] [0989] Signalling on Line SIP(1203)
    [2017-05-29 13:17:58,937] [0989] P-Preferred-Identity "Thomas Eink" <sip:4925649373185@starface.de> sipheader
    [2017-05-29 13:17:58,937] [0989] CALLERID(all) Thomas Eink <4925649373185> channel
    [2017-05-29 13:17:58,986] [0989] Dial SIP/1329.N720-0000094b to SIP/90785253273-0000094c
    [2017-05-29 13:18:00,241] [0989] SIP/90785253273-0000094c Channelstate is Ringing

  • Hallo Woestemar.


    Wir hatten ein Phänomen bei dem sich Tischapparate selbst angerufen haben.


    Die Herkunft des ganzes war im Prinzip extern, und zwar bei Zyxel Firewalls mit SIP-ALG, wenn die Funktionen direct-signalling, und direct-media abgestellt waren.


    Dann kamen komische SIP-Pakete von Russland usw. her, und kamen trotz aller Blockademögl. durch die Firewall.
    Wenn die 2 Funktionen abgestellt sind, durften die Telefone von überall auf deren IP-Adresse angerufen werden, und natürlich wurde immer ein Paketspam für div. Subnets geschickt, bis es geklingelt hat.
    Teilweise klingelte es dann im Minutentakt mit der Nummer 100 & 1000 auf dem Telefon.


    Nachdem wir direct-signalling und direct-media aktiviert hatten, war sofort Ruhe.


    //edit Siehe: SIP traffic from Internet will arrive to local network SIP server directly even there is no related firewall rule or NAT rule


    MfG


    Fabian

  • Danke für eure Antworten. Nur geht es bei mir NICHT um eingehende Anrufe, sondern um ausgehende Anrufe. Wie oben beschrieben wird eine xbeliebige Nummer aus unserem Adressstamm "geisterhaft" von alleine angewählt.

  • Hat denn der "Anrufer" einen UCC Client? Evtl. kann man aus dem UCC Client Log sehen, ob Anrufe durch eine 3rd Party Anwendung, tel-URLs, Hotkeys oder so ausgelöst werden.


    Gruß Wolfgang

  • Heute hatten wir das Phänomen wieder. Diesmal Geisteranruf 08:29 Uhr bei Hardy Schmitz.


    Was wir gesehen haben ist u.a. folgendes:


    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback |
    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback | Add to callback list. Filter: 10
    2017-06-19 08:29:00.8606 | 27 | INFO | UccAPI.UccPhoneClient.DoPlaceCallEx | placeCallWithPhone(+4959719990, IsFromTapi)
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | -> new UCI call
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccCallInternal.WriteToLog |


    und dann steht mehrfach im Log:


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:



    Hier der gesamte Auszug:


    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback |
    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback | Add to callback list. Filter: 10
    2017-06-19 08:29:00.8606 | 27 | INFO | UccAPI.UccPhoneClient.DoPlaceCallEx | placeCallWithPhone(+4959719990, IsFromTapi)
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | -> new UCI call
    2017-06-19 08:29:00.8676 | 30 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:00.8676 | 32 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:00.8676 | 32 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:00.8806 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:00.8806 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:00.8806 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateOutgoing
    2017-06-19 08:29:01.7927 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:01.7927 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:01.7927 | 37 | INFO | UccAPI.UccServerConnection.userStateChanged | Received new own state. Phone: Ringing, dnd=False, redirect always=False, chat: Available
    2017-06-19 08:29:01.7927 | 37 | INFO | UccAPI.UccServerConnection.userStateChanged | Presence monitoring: Own presence of chat client and UCI is in sync.
    2017-06-19 08:29:01.7927 | 1 | INFO | UcClient.WidgetOwnPresence.set_OwnTelephonyState | Own telephony state: Ringing
    2017-06-19 08:29:02.4347 | 32 | INFO | UccAPI.UccServerConnection.userStateChanged | Received new own state. Phone: Active, dnd=False, redirect always=False, chat: Available
    2017-06-19 08:29:02.4347 | 32 | INFO | UccAPI.UccServerConnection.userStateChanged | Presence monitoring: Own presence of chat client and UCI is in sync.
    2017-06-19 08:29:02.4347 | 1 | INFO | UcClient.WidgetOwnPresence.set_OwnTelephonyState | Own telephony state: Active
    2017-06-19 08:29:02.4407 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:02.4407 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:02.4407 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:02.4407 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:02.4747 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:02.4747 | 30 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:02.4897 | 32 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:02.4897 | 32 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:02.5247 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, OUTGOING
    2017-06-19 08:29:02.5247 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:04.8930 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, RINGBACK
    2017-06-19 08:29:04.8930 | 35 | INFO | UccAPI.UccCallInternal.Update | Trust call state CallStateRingback
    2017-06-19 08:29:04.8930 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateRingback
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:04.8930 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateRingback
    2017-06-19 08:29:10.8436 | 37 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, CONNECTED
    2017-06-19 08:29:10.8436 | 37 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:10.8436 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateConnected
    2017-06-19 08:29:10.8496 | 30 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, CONNECTED
    2017-06-19 08:29:10.8496 | 30 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:10.8496 | 32 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, CONNECTED
    2017-06-19 08:29:10.8496 | 32 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:10.8496 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, CONNECTED
    2017-06-19 08:29:10.8496 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:37.2462 | 33 | INFO | UccAPI.UccServerConnection.userStateChanged | Received new own state. Phone: Available, dnd=False, redirect always=False, chat: Available
    2017-06-19 08:29:37.2462 | 33 | INFO | UccAPI.UccServerConnection.userStateChanged | Presence monitoring: Own presence of chat client and UCI is in sync.
    2017-06-19 08:29:37.2462 | 1 | INFO | UcClient.WidgetOwnPresence.set_OwnTelephonyState | Own telephony state: Available
    2017-06-19 08:29:37.2462 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, HANGUP
    2017-06-19 08:29:37.2462 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    Call state: CallStateHangup
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 185 name: Thomas Eink, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1329.N720
    Channels: SIP/1329.N720-00003b0e
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    46cb9e8a135d41b309a9aabf4402e611@192.168.1.50:5060

    2017-06-19 08:29:37.2462 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | Remove hangup call: 626187fb-6a9b-4bb6-af16-44d583a36eb7
    2017-06-19 08:29:37.2462 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateHangup
    2017-06-19 08:29:37.2622 | 37 | INFO | UccAPI.UccPhoneClient.receiveCallState | 626187fb-6a9b-4bb6-af16-44d583a36eb7, HANGUP
    2017-06-19 08:29:37.2622 | 37 | INFO | UccAPI.UccPhoneClient.receiveCallState | Ignore, call is already in state HANGUP, avoid re-creation of removed call in UI
    2017-06-19 08:29:38.3473 | 27 | INFO | UccAPI.UccEventQueue.UnRegisterCallback |
    2017-06-19 08:29:38.3473 | 27 | INFO | UccAPI.UccEventQueue.UnRegisterCallback | Add to 'removal' list
    2017-06-19 08:29:58.7704 | 34 | WARN | UccAPI.UccEventQueue.ProcessEvents | Remove all closed or faulted UCC API callbacks
    2017-06-19 08:37:50.4035 | 45 | INFO | UccAPI.UccChatClient.UpdateChatHistoryFromServerNo |




    Danke für eure Hilfe.

  • Hallo Woestemar


    Könntest du mal das Log-Level im UCC-Client auf "Trace" setzen? (UCC-Client ==> Starface ==> Einstellungen ==> Logging ==> Log-Level)
    Und wenn es das nächste mal Auftritt da Logfile auf Trace senden?


    Mich würde vorallem hier interessieren: placeCallWithPhone(..., IsFromTapi <==) was im IsFromTapi war.


    Mfg


    Fabian


  • Habe ich auf TRACE gestellt. Denke auch, dass es irgendwo um diese Zeilen rum begründet liegt:


    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback |
    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback | Add to callback list. Filter: 10
    2017-06-19 08:29:00.8606 | 27 | INFO | UccAPI.UccPhoneClient.DoPlaceCallEx | placeCallWithPhone(+4959719990, IsFromTapi)

  • Habe ich auf TRACE gestellt. Denke auch, dass es irgendwo um diese Zeilen rum begründet liegt:


    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback |
    2017-06-19 08:29:00.8246 | 27 | INFO | UccAPI.UccEventQueue.RegisterCallback | Add to callback list. Filter: 10
    2017-06-19 08:29:00.8606 | 27 | INFO | UccAPI.UccPhoneClient.DoPlaceCallEx | placeCallWithPhone(+4959719990, IsFromTapi)


    Hier hat eine TAPI-Anwendung gewählt.


    Weitere Informationen gibt es dann im Log von unserem TAPI Service Provider (TSP):


    c:\ProgramData\STARFACE GmbH\TSP\logs\


    Gruß Wolfgang




  • 2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | LineOpen() for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | Create new StarfaceConnector
    2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | StarfaceConnector: Created new instance for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start connector for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | INFO | Starface.Tsp.Telephony.StarfaceConnector.Connect | CreateProxy: Creating proxy for WCF service at net.tcp://127.0.0.1:50002/starface/einktho/ucclient
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Connect | StarfaceConnector STARFACE Line einktho, connected to UCC client: True, connection state: ConnectionStateLoggedIn
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start: Connector for STARFACE Line einktho successfully started.
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | StartReconnectTimer: Activating reconnect timer.
    2017-06-19 08:29:00.8246 | 162 | TRACE | <no type>.Starface.Tsp.Telephony.TelephonyManager. | LineMakeCallAsync: Starting async fake procedure (dwRequestID=0x00010211, ...)
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | LineMakeCall(number=T0049 (5971) 999-0) for STARFACE Line einktho
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start connector for STARFACE Line einktho
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | Start: Connector for STARFACE Line einktho already started.
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | ParseTelNumber: (countyCode=0, number=T0049 (5971) 999-0)
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.PlaceCall | PlaceCall on StarfaceConnector STARFACE Line einktho, connected to UCC client: True, connection state: ConnectionStateLoggedIn
    2017-06-19 08:29:00.8636 | 162 | TRACE | Starface.Tsp.Telephony.Call..ctor | Call: New call created with id=626187fb-6a9b-4bb6-af16-44d583a36eb7, tapiCallHandle=0x00010200, callHandle=0x2, outbound=True
    2017-06-19 08:29:00.8646 | 162 | TRACE | NLog.LoggerImpl.Write | Line STARFACE Line einktho. Added call with id 626187fb-6a9b-4bb6-af16-44d583a36eb7. New call count: 1
    2017-06-19 08:29:00.8646 | 162 | TRACE | NLog.LoggerImpl.Write | Created new call object. UCI call id: 626187fb-6a9b-4bb6-af16-44d583a36eb7, TAPI call handle: 0x00010200, driver call handle: 0x2
    2017-06-19 08:29:00.8646 | 162 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Proceeding of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:00.8646 | 162 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Proceeding)
    2017-06-19 08:29:00.8646 | 162 | INFO | Starface.Tsp.Telephony.Line.MakeCall | Success, create new call object for uci call id 626187fb-6a9b-4bb6-af16-44d583a36eb7. TAPI call handle: 0x00010200, driver call handle: 0x2
    2017-06-19 08:29:00.8646 | 162 | TRACE | <no type>.Starface.Tsp.Telephony.TelephonyManager. | AsyncCompletionProc: TAPI <--- TSP # CompletionProc(dwRequestID=0x00010211, lResult=0x00000000)
    2017-06-19 08:29:00.8646 | 162 | TRACE | NLog.LoggerImpl.Write | AsyncCompletionProc: Returned from CompletionProc
    2017-06-19 08:29:00.8646 | 158 | TRACE | <no type>.Starface.Tsp.Telephony.TelephonyManager. | Origin: Outbound. Caller: / . Called: , . Connected: / . Forwarder: /
    2017-06-19 08:29:00.8646 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=, ownName=, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:00.8646 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:00.8646 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=, ownName=, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:00.8646 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:00.8816 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=, ownName=, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:00.8816 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:01.7947 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=, ownName=, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:01.7947 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:02.4427 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=, ownName=, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:02.4427 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:02.4427 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=, peerName=, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:02.4427 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:02.4827 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:02.4827 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:02.4867 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:02.4867 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:02.5517 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateOutgoing, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:02.5517 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Ignore call in state CallStateOutgoing
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateRingback, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | Outbound call with ID 626187fb-6a9b-4bb6-af16-44d583a36eb7 was started by TAPI -> report as proceeding before signaling ringback.
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:04.8950 | 160 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Proceeding of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:04.8950 | 160 | TRACE | NLog.LoggerImpl.Write | SignalInfo: TAPI <--- TSP # LineEvent.CallInfo(tapiCallHandle=0x00010200, infoChanged=00018000)
    2017-06-19 08:29:04.8950 | 160 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Proceeding)
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:04.8950 | 160 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:04.8950 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to RingBack of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:04.8950 | 160 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=RingBack)
    2017-06-19 08:29:10.8456 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateConnected, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:10.8456 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:10.8456 | 159 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:10.8456 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Connected of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Call.UpdateConnectedInfo | Connected. Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH.
    2017-06-19 08:29:10.8516 | 159 | TRACE | NLog.LoggerImpl.Write | SignalInfo: TAPI <--- TSP # LineEvent.CallInfo(tapiCallHandle=0x00010200, infoChanged=00020000)
    2017-06-19 08:29:10.8516 | 159 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Connected)
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateConnected, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:10.8516 | 159 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Connected of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Call.UpdateConnectedInfo | Connected. Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH.
    2017-06-19 08:29:10.8516 | 159 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Connected)
    2017-06-19 08:29:10.8516 | 160 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateConnected, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:10.8516 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:10.8516 | 160 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:10.8516 | 160 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Connected of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:10.8516 | 160 | TRACE | Starface.Tsp.Telephony.Call.UpdateConnectedInfo | Connected. Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH.
    2017-06-19 08:29:10.8516 | 160 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Connected)
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateConnected, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:10.8516 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:10.8516 | 159 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:10.8646 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Connected of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:10.8646 | 159 | TRACE | Starface.Tsp.Telephony.Call.UpdateConnectedInfo | Connected. Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH.
    2017-06-19 08:29:10.8646 | 159 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Connected)
    2017-06-19 08:29:37.2562 | 159 | TRACE | Starface.Tsp.Telephony.Line.ReceiveCallState | ReceiveCallState: id=626187fb-6a9b-4bb6-af16-44d583a36eb7, state=CallStateHangup, peerNumber=+4959719990, peerName=ZENTRALE Hardy Schmitz GmbH, ownNumber=185, ownName=Thomas Eink, forwarderdNumber=, forwarderName=, for STARFACE Line einktho
    2017-06-19 08:29:37.2562 | 159 | TRACE | Starface.Tsp.Telephony.Call.SetProperties | TAPI Call origin: Outbound
    2017-06-19 08:29:37.2562 | 159 | TRACE | NLog.LoggerImpl.Write | Peer: +4959719990 / ZENTRALE Hardy Schmitz GmbH. Own: 185 / Thomas Eink
    2017-06-19 08:29:37.2562 | 159 | INFO | Starface.Tsp.Telephony.Line.ReceiveCallState | Assuming passive hangup -> do not yet signal disconnected state
    2017-06-19 08:29:37.2562 | 159 | TRACE | NLog.LoggerImpl.Write | ReceiveCallRemoved(id=626187fb-6a9b-4bb6-af16-44d583a36eb7 for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 157 | INFO | Starface.Tsp.Telephony.Line.OnTimerElapsed | Signal disconnected call 626187fb-6a9b-4bb6-af16-44d583a36eb7
    2017-06-19 08:29:38.3383 | 157 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Disconnected of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 1.
    2017-06-19 08:29:38.3383 | 157 | TRACE | Starface.Tsp.Telephony.Call.SignalState | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Disconnected)
    2017-06-19 08:29:38.3383 | 157 | TRACE | Starface.Tsp.Telephony.Call.SetState | SetState: Setting state to Idle of call 626187fb-6a9b-4bb6-af16-44d583a36eb7, mode 0.
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | LineDropCallAsync: Starting async fake procedure (dwRequestID=0x000101EE ...
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | DropCall: TAPI ---> TSP # LineDrop(callHandle=0x2)
    2017-06-19 08:29:38.3383 | 157 | TRACE | NLog.LoggerImpl.Write | SignalState: LineEvent.CallState(tapiCallHandle=0x00010200, state=Idle)
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | DropCall: STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start connector for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | Start: Connector for STARFACE Line einktho already started.
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | Call cannot be dropped due to current call/line state.
    2017-06-19 08:29:38.3383 | 162 | TRACE | <no type>.Starface.Tsp.Telephony.TelephonyManager. | AsyncCompletionProc: TAPI <--- TSP # CompletionProc(dwRequestID=0x000101EE, lResult=0x00000000)
    2017-06-19 08:29:38.3383 | 162 | TRACE | NLog.LoggerImpl.Write | AsyncCompletionProc: Returned from CompletionProc
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | CloseCall: TAPI ---> TSP # LineCloseCall(callHandle=0x2)
    2017-06-19 08:29:38.3383 | 155 | TRACE | Starface.Tsp.Telephony.CallManager.CloseCall | CloseCall: Call found, removing from list (id=626187fb-6a9b-4bb6-af16-44d583a36eb7, number=+4959719990, phone=)
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Line STARFACE Line einktho. Removed call with id 626187fb-6a9b-4bb6-af16-44d583a36eb7. New call count: 0
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | LineClose() for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 155 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Stop | Stop connector for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | StopReconnectTimer: Deactivating reconnect timer.
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Destroy connector for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 155 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Disconnec | StarfaceConnector STARFACE Line einktho, connected to UCC client: False, connection state: ConnectionStateLoggedOut
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Connector for STARFACE Line einktho successfully stopped.
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Release StarfaceConnector
    2017-06-19 08:29:38.3383 | 155 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Stop | Stop connector for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Destroy connector for STARFACE Line einktho
    2017-06-19 08:29:38.3383 | 155 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Disconnec | StarfaceConnector STARFACE Line einktho, connected to UCC client: False, connection state: ConnectionStateLoggedOut
    2017-06-19 08:29:38.3383 | 155 | TRACE | NLog.LoggerImpl.Write | Connector for STARFACE Line einktho successfully stopped.

  • Das ist der eine Anruf. Was "sehe" ich denn da jetzt? Und danke für den Hinweis mit dem TSP-Log. Reihe ich in die Liste der Starface-Logs ein ;)

  • Du siehst hier, dass eine TAPI Anwendung die TAPI line "STARFACE Line einktho" öffnet und einen Ruf startet:


    2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | LineOpen() for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | Create new StarfaceConnector
    2017-06-19 08:29:00.8106 | 162 | TRACE | NLog.LoggerImpl.Write | StarfaceConnector: Created new instance for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start connector for STARFACE Line einktho
    2017-06-19 08:29:00.8106 | 162 | INFO | Starface.Tsp.Telephony.StarfaceConnector.Connect | CreateProxy: Creating proxy for WCF service at net.tcp://127.0.0.1:50002/starface/einktho/ucclient
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Connect | StarfaceConnector STARFACE Line einktho, connected to UCC client: True, connection state: ConnectionStateLoggedIn
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start: Connector for STARFACE Line einktho successfully started.
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | StartReconnectTimer: Activating reconnect timer.
    2017-06-19 08:29:00.8246 | 162 | TRACE | <no type>.Starface.Tsp.Telephony.TelephonyManager. | LineMakeCallAsync: Starting async fake procedure (dwRequestID=0x00010211, ...)
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | LineMakeCall(number=T0049 (5971) 999-0) for STARFACE Line einktho
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.Start | Start connector for STARFACE Line einktho
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | Start: Connector for STARFACE Line einktho already started.
    2017-06-19 08:29:00.8246 | 162 | TRACE | NLog.LoggerImpl.Write | ParseTelNumber: (countyCode=0, number=T0049 (5971) 999-0)
    2017-06-19 08:29:00.8246 | 162 | TRACE | Starface.Tsp.Telephony.StarfaceConnector.PlaceCall | PlaceCall on StarfaceConnector STARFACE Line einktho, connected to UCC client: True, connection state: ConnectionStateLoggedIn


    Jetzt kannst Du mal schauen, welche Anwendungen auf dem PC wohl per TAPI wählen könnten.


    Gruß Wolfgang

  • Ich bin nebenbei noch auf das hier gestoßen: http://www.andysblog.de/termin…he-telefon-verwendet-wird .... da steht unter anderem drin:


    Hinweis auf die Ursache des Problems


    Nach langer Recherche fand sich ein Hinweis auf die Ursache dieses Phänomens.


    Kurz gesagt: Anwendungen die mittels der Windows-Funktion tapiRequestMakeCall eine Anwahl starten sind davon betroffen, dass die „dialer.exe“ die zuerst gestartet wurde gewonnen hat. Es wird schlicht nicht nach Benutzer unterschieden.


    Bei Einzelplätzen kommt das nicht zum tragen. Das Problem ist somit auf Terminalserver begrenzt, auf dem mehrere Anwender gleichzeitig arbeiten. Die Verwendung einer anderen CTI dürfte somit Zwecklos sein. Und tatsächlich findet sich dazu ein Hinweis bei einem anderen Hersteller:


    Funktioniert der TAPIRequestMakeCall auch auf Terminal-Servern?


    Leider nein, wenn mehrere User dialit32.exe oder dialier.exe auf einem Terminalserver gestartet haben, wird immer der erste der gestartet hat den Request beantworten, also das falsche Telefon wird u.U. wählen. Der TAPIRequestmakeCall ist systembedingt dafür nicht geeignet.


    Das Problem ist somit genereller Natur.


    Ist das auch noch eine Option?

  • TAPIRequestMakeCall ist eine "TAPI Funktion für Arme" - damit meine ich: Wenn ich per TAPI wählen will und keine Lust auf den ganzen Line-Öffnen etc. Kram habe, kann ich damit einen Ruf auslösen. Es muss dann eine TAPI-Anwendung geben, welche diese Funktion unterstützt. Dies ist keine Funktion, welche in einem TAPI Service Provider implementiert ist, sondern in einer TAPI Anwendung (z.B. dem Windows Dialer). Der STARFACE UCC Client unterstützt diese Funktion übrigens nicht. D.h. wenn auf einem PC eine Anwendung per TAPIRequestMakeCall wählen will, braucht es neben dem UCC Client noch eine TAPI Anwendung, welche TAPIRequestMakeCall implementiert.


    Hast Du denn eine Terminal Server Umgebung?


    Gruß Wolfgang

  • Das hier stützt meine Vermutung. Anderer Kollege hat heute tatsächlich versucht "Hardy Schmitz Zentrale 059719990" anzurufen. Genau gleiche Zeit: 08:29 Uhr. Das stützt ja die These, dass wir hier eventuell ein Problem mit der Dialer.exe haben im Terminalserverbetrieb. Oder?



    2017-06-19 08:29:15.9191 | 1 | INFO | UcClient.ContactSearchController.ctrl_FunctionKeyP | Place call to: +4959719990
    2017-06-19 08:29:15.9191 | 16 | INFO | UccAPI.UccPhoneClient.DoPlaceCallEx | placeCallWithPhone(+4959719990, IsFromAddressbook)
    2017-06-19 08:29:15.9191 | 29 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:15.9191 | 29 | INFO | UccAPI.UccPhoneClient.receiveCallState | -> new UCI call
    2017-06-19 08:29:15.9191 | 29 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:15.9351 | 31 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:15.9351 | 31 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:15.9351 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:15.9351 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels:
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:15.9521 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateOutgoing
    2017-06-19 08:29:17.2952 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:17.2952 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:17.2952 | 40 | INFO | UccAPI.UccServerConnection.userStateChanged | Received new own state. Phone: Ringing, dnd=False, redirect always=False, chat: Available
    2017-06-19 08:29:17.2952 | 40 | INFO | UccAPI.UccServerConnection.userStateChanged | Presence monitoring: Own presence of chat client and UCI is in sync.
    2017-06-19 08:29:17.2952 | 1 | INFO | UcClient.WidgetOwnPresence.set_OwnTelephonyState | Own telephony state: Ringing
    2017-06-19 08:29:19.4024 | 31 | INFO | UccAPI.UccServerConnection.userStateChanged | Received new own state. Phone: Active, dnd=False, redirect always=False, chat: Available
    2017-06-19 08:29:19.4024 | 31 | INFO | UccAPI.UccServerConnection.userStateChanged | Presence monitoring: Own presence of chat client and UCI is in sync.
    2017-06-19 08:29:19.4024 | 1 | INFO | UcClient.WidgetOwnPresence.set_OwnTelephonyState | Own telephony state: Active
    2017-06-19 08:29:19.4024 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:19.4024 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: name: , Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:19.4144 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:19.4144 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : name:
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:19.4424 | 40 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:19.4424 | 40 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:19.4484 | 29 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:19.4484 | 29 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:

    2017-06-19 08:29:19.4944 | 33 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, OUTGOING
    2017-06-19 08:29:19.4944 | 33 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateOutgoing
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

    2017-06-19 08:29:21.7527 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, RINGBACK
    2017-06-19 08:29:21.7527 | 35 | INFO | UccAPI.UccCallInternal.Update | Trust call state CallStateRingback
    2017-06-19 08:29:21.7537 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateRingback
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

    2017-06-19 08:29:21.7537 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateRingback
    2017-06-19 08:29:39.7805 | 35 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, CONNECTED
    2017-06-19 08:29:39.7805 | 35 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

    2017-06-19 08:29:39.7805 | 1 | INFO | UcClient.VisualCallEgg.UpdateCallState | New UCI call state: CallStateConnected
    2017-06-19 08:29:39.7805 | 40 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, CONNECTED
    2017-06-19 08:29:39.7935 | 40 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

    2017-06-19 08:29:39.7935 | 29 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, CONNECTED
    2017-06-19 08:29:39.7935 | 29 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

    2017-06-19 08:29:39.8055 | 31 | INFO | UccAPI.UccPhoneClient.receiveCallState | 4fd53746-d751-46f0-8b96-fb085f4ab3de, CONNECTED
    2017-06-19 08:29:39.8055 | 31 | INFO | UccAPI.UccCallInternal.WriteToLog |


    UCI Call ID: 4fd53746-d751-46f0-8b96-fb085f4ab3de
    Call state: CallStateConnected
    Asterisk cause: 0, SIP Call Reason: 0
    IsCaller: True, Caller number: 234 name: Tobias Keller, Called number : 0004959719990 name: ZENTRALE Hardy Schmitz GmbH
    Is recorded: False, is recorder: False, is record owner: False
    Phone names: SIP/1056.N720
    Channels: SIP/1056.N720-00003b10
    Has local channel: False
    Local SIP call:
    Related SIP Calls:
    38a83b6769d8e9ce2beeb5dd413ab3ba@192.168.1.50:5060

  • Im TSP-Log steht dann nichts mehr vom Kollegen, der den eigentlichen Anruf initiert hat (kelletob). Nur noch der Kollege, der den Geisteranruf bekommen hat (einktho).

  • TAPIRequestMakeCall ist eine "TAPI Funktion für Arme" - damit meine ich: Wenn ich per TAPI wählen will und keine Lust auf den ganzen Line-Öffnen etc. Kram habe, kann ich damit einen Ruf auslösen. Es muss dann eine TAPI-Anwendung geben, welche diese Funktion unterstützt. Dies ist keine Funktion, welche in einem TAPI Service Provider implementiert ist, sondern in einer TAPI Anwendung (z.B. dem Windows Dialer). Der STARFACE UCC Client unterstützt diese Funktion übrigens nicht. D.h. wenn auf einem PC eine Anwendung per TAPIRequestMakeCall wählen will, braucht es neben dem UCC Client noch eine TAPI Anwendung, welche TAPIRequestMakeCall implementiert.


    Hast Du denn eine Terminal Server Umgebung?


    Gruß Wolfgang


    Das Problem tritt nur in der TS-Umgebung auf. Denke, dass wir dann den Schuldigen (Dialer.exe) gefunden haben. Das ERP geht auf die Dialer.exe und dann auf die Starface. Die erste Instanz der Dialer.exe wird scheinbar IMMER angesprochen, weil diese NICHT mehr geschlossen wird.

  • Kann ich einen anderen Weg auf dem TS gehen? Z.B. über parametrisierten EXE Aufruf? Oder Callto-Links die ich in den Untiefen des ERPs im Script einbaue?!?!


    Ich geh jetzt mal stumpf davon aus, dass das unser Problem ist, oder?

  • Kann ich einen anderen Weg auf dem TS gehen? Z.B. über parametrisierten EXE Aufruf? Oder Callto-Links die ich in den Untiefen des ERPs im Script einbaue?!?!


    Ich geh jetzt mal stumpf davon aus, dass das unser Problem ist, oder?



    Ich weiss zwar nicht, ob das auf dem Terminalserver funktioniert.


    Siehe: https://knowledge.starface.de/…ARFACE+Client+for+Windows ==> STARFACE Client.exe PlaceCall "+49 123 45678"


    MfG


    Fabian


  • Der Link bezieht sich auf den alten Client. Akuell ist:


    StarfaceUcClient.exe /PHONENUMBER="12345"


    oder einen callto oder tel link auslösen.



    Gruß Wolfgang

Jetzt mitmachen!

Sie haben noch kein Benutzerkonto auf unserer Seite? Registrieren Sie sich kostenlos und nehmen Sie an unserer Community teil!