[Problem] Anlagenverbund Call landet immer auf Abwurfplatz (lokal)

  • Hallo zusammen,


    vielleicht kennt jemand das Problem und kann mir helfen?
    Ich habe 3 Anlagen im Verbund, "von heute auf morgen" kann ich nicht mehr intern telefonieren etc., Status (Besetzt/Frei) werden angezeigt.
    Die Analgen sind über Site-2-Site VPN verbunden, ich komme auch zum Administrieren auf die Anlage, die Firewall blockt nix auf den netzen.


    Wenn ich von 513 intern 211 für Prefix 2 UserTelNr. 11 wähle lande ich auf dem Lokalen Abwurfplatz 500.


    Ich habe bereits viel verscuht und auch einen neuen Anlagenverbund aufgebaut, leider alles erfolglos :(
    Hoffentlich hat hier jemand eine gute IDEE :)


    Hier der Support Log:
    [2019-04-30 11:44:32,068] [0362] ********* Call created *********
    [2019-04-30 11:44:32,068] [0362] Starting call routing : SIP/1001.ylnkt46-00000226|1556617472.755 dial number 211 CallerId Horst<514>
    [2019-04-30 11:44:32,129] [0362] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,160] [0362] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,182] [0362] Routing call "Horst <514>" to number 211 over service UserService
    [2019-04-30 11:44:32,183] [0362] CallLeg e2b87118-1f86-46fe-84f6-c724b21d5c47
    [2019-04-30 11:44:32,185] [0362] Got dialstatus DialReturnCodes(hc=CALL_REJECTED, ds=CHANUNAVAIL, cr=NOT_CONNECTED)
    [2019-04-30 11:44:32,187] [0362] Forward to deposit 500. Original number = 211
    [2019-04-30 11:44:32,190] [0362] Forwarding call to 500 ALWAYS
    [2019-04-30 11:44:32,231] [0362] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,239] [0362] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,264] [0362] Routing call "Horst <514>" to number 500 over service CallHuntingService
    [2019-04-30 11:44:32,264] [0362] CallLeg e2b87118-1f86-46fe-84f6-c724b21d5c47
    [2019-04-30 11:44:32,370] [0362] Forwarding call to 513 ALWAYS
    [2019-04-30 11:44:32,371] [0362] Got dialstatus DialReturnCodes(hc=STARFACE_REDIRECTED, ds=NOANSWER, cr=NOT_CONNECTED)
    [2019-04-30 11:44:32,407] [0362] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,416] [0362] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-04-30 11:44:32,438] [0362] Routing call "Horst Sindermann <514>" to number 513 over service UserService
    [2019-04-30 11:44:32,438] [0362] CallLeg e2b87118-1f86-46fe-84f6-c724b21d5c47
    [2019-04-30 11:44:32,601] [0362] Dial SIP/1001.ylnkt46-00000226 to SIP/1018.ylnkt46-00000227
    [2019-04-30 11:44:32,665] [0362] SIP/1018.ylnkt46-00000227 Channelstate is Ringing
    [2019-04-30 11:44:34,281] [0362] SIP/1001.ylnkt46-00000226 HangupRequestEvent
    [2019-04-30 11:44:34,331] [0362] SIP/1018.ylnkt46-00000227 Hangup Cause: Normal Clearing
    [2019-04-30 11:44:34,358] [0362] SIP/1001.ylnkt46-00000226 Hangup Cause: null
    [2019-04-30 11:44:34,391] [0362] ********* Call finished *********



    Fedprotocol Log, aber keine Zeitgleichen Ereignisse:
    [2019-04-30 11:14:39,787] INFO DemandForwardingBridgeSupport localhost bridge to localbroker stopped
    [2019-04-30 11:14:39,787] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://10.11.10.160:3090-83f84770-00ec-4d9b-9346-881a2638dec4:BrokerService[localbroker] stopped
    [2019-04-30 11:15:34,582] WARN Transport Transport Connection to: tcp://10.11.10.160:45182 failed: org.apache.activemq.transport.InactivityIOException: Channel was inactive for too (>30000) long: tcp://10.11.10.160:45182
    [2019-04-30 11:25:17,875] INFO DiscoveryNetworkConnector Establishing network connection from vm://localbroker to tcp://10.11.10.160:3090
    [2019-04-30 11:25:18,071] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://10.11.10.160:3090-83f84770-00ec-4d9b-9346-881a2638dec4:BrokerService[localbroker] started
    [2019-04-30 11:25:18,256] INFO DemandForwardingBridgeSupport Network connection between vm://localbroker#16 and tcp:///10.11.10.160:3090@45090 (localbroker) has been established.



    Federation Log:
    [2019-04-30 11:14:35,974] INFO de.vertico.starface.federation.site.SiteInfoExchanger 'CGN' goes offline
    [2019-04-30 11:14:39,743] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Peer removed 10.11.10.160
    [2019-04-30 11:14:39,744] INFO de.vertico.starface.federation.jms.JmsService4 Service removed: tcp://10.11.10.160:3090-83f84770-00ec-4d9b-9346-881a2638dec4
    [2019-04-30 11:14:39,744] INFO de.vertico.starface.federation.jms.JmsService4 Shutdown network connector: tcp://10.11.10.160:3090-83f84770-00ec-4d9b-9346-881a2638dec4
    [2019-04-30 11:25:17,874] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Online peer found 10.11.10.160
    [2019-04-30 11:25:17,874] INFO de.vertico.starface.federation.jms.JmsService4 Starting network connector to tcp://10.11.10.160:3090-83f84770-00ec-4d9b-9346-881a2638dec4
    [2019-04-30 11:25:21,195] INFO de.vertico.starface.federation.routing.InterconnRoutingManager Received message of type PullConfigEvent
    [2019-04-30 11:25:21,238] INFO de.vertico.starface.federation.site.SiteInfoExchanger a2a3ed87-d39d-460f-bfb4-989aa839cd0f goes online
    [2019-04-30 11:25:21,248] INFO de.vertico.starface.federation.routing.InterconnRoutingManager Received message of type PushConfigEvent
    [2019-04-30 11:25:22,196] INFO de.vertico.starface.federation.site.SiteInfoExchanger Initial data received from CGN@10.11.10.160 uuid:a2a3ed87-d39d-460f-bfb4-989aa839cd0f
    [2019-04-30 11:44:13,095] INFO de.vertico.starface.persistence.connector.federation.FederationHandler initSiteHandler: everything ok



    DANKE und viele Grüße an die Community

  • Gegenstelle (Anlagenprefix 2)


    Support Log: da ich nicht mehr dran komme (100 Zeilen) neu ausgelöst...
    [2019-04-30 12:52:43,758] [0792] Routing call "Ingrid<11>" to number e1017 over service BLFServiceSelector
    [2019-04-30 12:52:43,758] [0792] CallLeg f71a5a22-d652-445e-a8c3-53071a72b1c8
    [2019-04-30 12:52:43,763] [0792] Got dialstatus DialReturnCodes(hc=NOTDEFINED, ds=UNKNOWN, cr=UNKNOWN)



    Fedprotocol:
    [2019-04-30 11:14:40,860] INFO DemandForwardingBridgeSupport localhost bridge to localbroker stopped
    [2019-04-30 11:14:40,861] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://192.168.1.181:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838:BrokerService[localbroker] stopped
    [2019-04-30 11:14:44,848] INFO DemandForwardingBridgeSupport localhost bridge to localbroker stopped
    [2019-04-30 11:14:44,849] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://192.168.1.105:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838:BrokerService[localbroker] stopped
    [2019-04-30 11:15:19,442] WARN Transport Transport Connection to: tcp://192.168.1.105:52450 failed: org.apache.activemq.transport.InactivityIOException: Channel was inactive for too (>30000) long: tcp://192.168.1.105:52450
    [2019-04-30 11:15:40,383] WARN Transport Transport Connection to: tcp://192.168.1.181:39548 failed: org.apache.activemq.transport.InactivityIOException: Channel was inactive for too (>30000) long: tcp://192.168.1.181:39548
    [2019-04-30 11:25:20,480] INFO DiscoveryNetworkConnector Establishing network connection from vm://localbroker to tcp://192.168.1.105:3090
    [2019-04-30 11:25:20,611] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://192.168.1.105:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838:BrokerService[localbroker] started
    [2019-04-30 11:25:20,615] INFO DiscoveryNetworkConnector Establishing network connection from vm://localbroker to tcp://192.168.1.181:3090
    [2019-04-30 11:25:20,780] INFO NetworkConnector Network Connector DiscoveryNetworkConnector:tcp://192.168.1.181:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838:BrokerService[localbroker] started
    [2019-04-30 11:25:20,785] INFO DemandForwardingBridgeSupport Network connection between vm://localbroker#12 and tcp:///192.168.1.105:3090@36554 (localbroker) has been established.
    [2019-04-30 11:25:20,891] INFO DemandForwardingBridgeSupport Network connection between vm://localbroker#14 and tcp:///192.168.1.181:3090@48152 (localbroker) has been established.


    Federation Log:
    [2019-04-30 11:14:31,653] INFO de.vertico.starface.federation.site.SiteInfoExchanger 'IVB' goes offline
    [2019-04-30 11:14:34,654] INFO de.vertico.starface.federation.site.SiteInfoExchanger 'KG' goes offline
    [2019-04-30 11:14:40,819] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Peer removed 192.168.1.181
    [2019-04-30 11:14:40,819] INFO de.vertico.starface.federation.jms.JmsService4 Service removed: tcp://192.168.1.181:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:14:40,819] INFO de.vertico.starface.federation.jms.JmsService4 Shutdown network connector: tcp://192.168.1.181:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:14:44,820] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Peer removed 192.168.1.105
    [2019-04-30 11:14:44,820] INFO de.vertico.starface.federation.jms.JmsService4 Service removed: tcp://192.168.1.105:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:14:44,820] INFO de.vertico.starface.federation.jms.JmsService4 Shutdown network connector: tcp://192.168.1.105:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:25:20,480] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Online peer found 192.168.1.105
    [2019-04-30 11:25:20,480] INFO de.vertico.starface.federation.jms.JmsService4 Starting network connector to tcp://192.168.1.105:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:25:20,615] INFO de.vertico.starface.federation.jms.discovery.RendezvousDiscoveryAgent Online peer found 192.168.1.181
    [2019-04-30 11:25:20,615] INFO de.vertico.starface.federation.jms.JmsService4 Starting network connector to tcp://192.168.1.181:3090-72d15c5c-5ad8-4945-be33-baf9bb80c838
    [2019-04-30 11:25:21,123] INFO de.vertico.starface.federation.site.SiteInfoExchanger 7f5d6922-8cac-4ca2-9f6a-a45e45835f5a goes online
    [2019-04-30 11:25:21,288] INFO de.vertico.starface.federation.routing.InterconnRoutingManager Received message of type PushConfigEvent
    [2019-04-30 11:25:21,546] INFO de.vertico.starface.federation.routing.InterconnRoutingManager Received message of type PushConfigEvent
    [2019-04-30 11:25:22,096] INFO de.vertico.starface.federation.site.SiteInfoExchanger Initial data received from IVB@192.168.1.105 uuid:7f5d6922-8cac-4ca2-9f6a-a45e45835f5a
    [2019-04-30 11:25:24,625] INFO de.vertico.starface.federation.site.SiteInfoExchanger ca17a73b-c7bd-41a8-a3d9-4c52f06aa893 goes online
    [2019-04-30 11:27:04,680] INFO de.vertico.starface.federation.site.SiteInfoExchanger Initial data received from KG@192.168.1.181 uuid:ca17a73b-c7bd-41a8-a3d9-4c52f06aa893

  • Kurzer nachtag, im Verbund sind insgesamt 3 Anlagen (192.168.1.105 AS IVB / 192.168.1.181 AS KG / 10.11.10.161 AS CGN)


    [2019-05-14 10:43:48,790] [0843] ********* Call created *********
    [2019-05-14 10:43:48,790] [0843] Starting call routing : SIP/1001.ylnkt46-000004af|1557823428.1746 dial number a2094 CallerId Horst<514>
    [2019-05-14 10:43:48,872] [0843] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-05-14 10:43:48,921] [0843] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-05-14 10:43:48,947] [0843] Routing call "Horst <514>" to number a2094 over service UserService
    [2019-05-14 10:43:48,948] [0843] CallLeg cf1d1d25-db9b-47c1-a47e-d9f101ed3b24
    [2019-05-14 10:43:48,948] [0843] Got dialstatus DialReturnCodes(hc=CALL_REJECTED, ds=CHANUNAVAIL, cr=NOT_CONNECTED)
    [2019-05-14 10:43:48,950] [0843] Forward to deposit 500. Original number = 211
    [2019-05-14 10:43:48,955] [0843] Forwarding call to 500 ALWAYS
    [2019-05-14 10:43:48,988] [0843] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-05-14 10:43:48,996] [0843] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-05-14 10:43:49,019] [0843] Routing call "Horst <514>" to number 500 over service CallHuntingService
    [2019-05-14 10:43:49,019] [0843] CallLeg cf1d1d25-db9b-47c1-a47e-d9f101ed3b24
    [2019-05-14 10:43:49,086] [0843] Forwarding call to 513 ALWAYS
    [2019-05-14 10:43:49,087] [0843] Got dialstatus DialReturnCodes(hc=STARFACE_REDIRECTED, ds=NOANSWER, cr=NOT_CONNECTED)
    [2019-05-14 10:43:49,127] [0843] Relevance check in "Öffnungszeiten" on callstage onAllIncomingCalls
    [2019-05-14 10:43:49,136] [0843] Relevance check in "BLOCKIEREN" on callstage onAllIncomingCalls
    [2019-05-14 10:43:49,162] [0843] Routing call "Horst <514>" to number 513 over service UserService
    [2019-05-14 10:43:49,162] [0843] CallLeg cf1d1d25-db9b-47c1-a47e-d9f101ed3b24
    [2019-05-14 10:43:49,338] [0843] Dial SIP/1001.ylnkt46-000004af to SIP/1018.ylnkt46-000004b0
    [2019-05-14 10:43:49,447] [0843] SIP/1018.ylnkt46-000004b0 Channelstate is Ringing
    [2019-05-14 10:43:51,226] [0843] SIP/1001.ylnkt46-000004af HangupRequestEvent
    [2019-05-14 10:43:51,236] [0843] SIP/1018.ylnkt46-000004b0 Hangup Cause: Normal Clearing
    [2019-05-14 10:43:51,272] [0843] SIP/1001.ylnkt46-000004af Hangup Cause: null
    [2019-05-14 10:43:51,289] [0843] ********* Call finished *********


    Heute war der Support-Log leicht verändert... [2019-05-14 10:43:49,087] [0843] Got dialstatus DialReturnCodes(hc=STARFACE_REDIRECTED, ds=NOANSWER, cr=NOT_CONNECTED)
    Das komische ist eben, das eine Richtung funktioniert und die andere nicht.


    Vielleicht hat noch jemand einen Vorschlag ? :)
    DANKE

    2 Mal editiert, zuletzt von Hotte ()

  • Leider nein,


    ich finde in beiden Firewalls nix im Log (Sophos SG/XG)
    Das neu einrichten des Verbundes brachte mir nur arbeit, empfehle ich also eher nicht.
    Bis heute geht der Anruf nur in eine Richtung! :(


    Ansonsten komme ich von beiden Seiten auf die Anlage (Webinterface) und erreiche auch andere Ports des VPN-Netzes z.B. 8443,4444 weshalb die Firewall-mit der-regel ALLOW NetzA|NetzB ANY NetzA|NetzB auch passen sollte :)


    Ich werde mir nochmal beide pcaps angucken und dann ein Ticket aufmachen.

  • Weiss jemand warum der Anlagenverbund (Ist Verbunden, Status (GRÜN/ROT) wird korrekt angezeigt keine Sprachverbindung/Anruf aufbaut ?


    bzw. was bedeutet Got dialstatus DialReturnCodes(hc=CALL_REJECTED, ds=CHANUNAVAIL, cr=NOT_CONNECTED) genau ?
    Ich habe hier beide SF und beide Gateways/Firewalls mit Live-Log auf dem Bildschirm und komme nicht mehr weiter...


    [2020-09-18 15:47:53,800] [0002] Routing call "click2dial: 210 <210>" to number 210 over service UserService
    [2020-09-18 15:47:53,800] [0002] CallLeg 88126591-7618-4bc9-b796-0bcca694fabb
    [2020-09-18 15:47:53,801] [0002] Got dialstatus DialReturnCodes(hc=CALL_REJECTED, ds=CHANUNAVAIL, cr=NOT_CONNECTED)
    [2020-09-18 15:47:53,802] [0002] Forward to deposit 500. Original number = 210

Jetzt mitmachen!

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