Slightly garbled audio

Discussion in '3CX Phone System - General' started by pcunite, Apr 13, 2010.

Thread Status:
Not open for further replies.
  1. pcunite

    Joined:
    Apr 12, 2010
    Messages:
    29
    Likes Received:
    0
    Running Skype client 4.2.0.158 on WIndows XP PRO x64, 3CX 8.0.10824.716, and Skype Gateway 2.0.9919.0.

    The XP box is an Intel QUAD Q6600 with 8GB ram, two sata II 500 gig drives mirrored.

    The XP box has no one logged in and I am connected to the 3CX pbx via X-Lite softphone 3.0 build 56125. Is there anything I need to do? Is the Skype Client that buggy configured this way?
     
  2. Montclairguy

    Joined:
    Feb 23, 2010
    Messages:
    68
    Likes Received:
    0
    Similar, but less powered setup, and I have the same problem. This is apparently a known issue that 3CX is trying to debug. I had to opt for a different gateway product in the meantime.
     
  3. pcunite

    Joined:
    Apr 12, 2010
    Messages:
    29
    Likes Received:
    0
    I am going to switch from Skype to a standard SIP for outgoing and see how that goes. I think 3CX is to be commended for trying, but Skype making people use their GUI is the real problem.
     
  4. SY

    SY Well-Known Member
    3CX Support

    Joined:
    Jan 26, 2007
    Messages:
    1,825
    Likes Received:
    2
    What is the issue?

    Thanks
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  5. Montclairguy

    Joined:
    Feb 23, 2010
    Messages:
    68
    Likes Received:
    0
    Audio recordings attached to demonstrate the garbled audio issue of 3CX's Skype gateway versus a different Skype gateway.

    Secondly, there is an Early Media issue with 3CX's gateway. I posted about it several days ago, but it has not been addressed.

    http://www.3cx.com/forums/early-media-problems-14591.html
     

    Attached Files:

  6. Montclairguy

    Joined:
    Feb 23, 2010
    Messages:
    68
    Likes Received:
    0
    What the heck happened to the remaining thread for April 16th? It's all deleted? I was having a conversation here with Stephan and providing logs to debug latency.
     
  7. Montclairguy

    Joined:
    Feb 23, 2010
    Messages:
    68
    Likes Received:
    0
    Luckily, I still have the missing content open in another tab. Here's the last missing post:

    SipToSis Log:
    Code:
    2010-04-16 14:47:32,250 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - incoming sip call from "Me" <sip:10010@127.0.0.1:5060> callee=<sip:1800xxxxxxx@192.168.1.4:5065>
    2010-04-16 14:47:32,250 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - Skype Dial:1800xxxxxxx
    2010-04-16 14:47:33,250 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - Actual destination:+1800xxxxxxx
    2010-04-16 14:47:33,375 [AsyncSkypeMessageSender-99] INFO  local.ua.SSCallChannel.#C0 - skypeCallStatus[430861] - ROUTING
    2010-04-16 14:47:37,765 [AsyncSkypeMessageSender-99] INFO  local.ua.SSCallChannel.#C0 - skypeCallStatus[430861] - RINGING
    2010-04-16 14:47:38,062 [AsyncSkypeMessageSender-99] INFO  local.ua.SSCallChannel.#C0 - skypeCallStatus[430861] - EARLYMEDIA
    2010-04-16 14:47:38,187 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SkypeUserAgent.#C0 - Starting codec: PCMU/8000(0) rfc2833Type(101)
    2010-04-16 14:47:38,187 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - CallConfirmed Active
    2010-04-16 14:47:38,187 [local.ua.SkypeRtpSender.T4] INFO  local.ua.SkypeRtpSender.T4 - RTP media target: 127.0.0.1:7012
    2010-04-16 14:47:38,203 [local.ua.SkypeRtpReceiver.T5] INFO  local.ua.SkypeRtpReceiver.T5 - RtpSender Address locked.
    2010-04-16 14:47:39,546 [AsyncSkypeMessageSender-100] INFO  local.ua.SSCallChannel.#C0 - skypeCallStatus[430861] - INPROGRESS
    2010-04-16 14:47:39,546 [AsyncSkypeMessageSender-100] INFO  local.ua.SSCallChannel.#C0 - Call Rate: Free
    2010-04-16 14:47:52,375 [local.ua.SkypeAudioSenderServer.T7] INFO  local.ua.SkypeAudioSenderServer.T7 - * SkypeAudioSender stats - packets:654 underRuns:1 maxLatency:40ms avgLatency:21ms
    2010-04-16 14:47:52,390 [local.ua.SkypeAudioReceiverServer.T6] INFO  local.ua.SkypeAudioReceiverServer.T6 - * SkypeAudioReceiver stats - packets:427
    2010-04-16 14:47:52,390 [local.ua.SkypeRtpSender.T4] INFO  local.ua.SkypeRtpSender.T4 - * RTPSender stats - packets:11642 underRuns:262 maxLatency:0ms avgLatency:0ms
    2010-04-16 14:47:52,468 [local.ua.SkypeRtpReceiver.T5] INFO  local.ua.SkypeRtpReceiver.T5 - * RTPReceiver stats - packets:704 timeOuts:1
    2010-04-16 14:47:52,531 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - OUTGOING_PSTN From: "Me" <sip:10010@127.0.0.1:5060> To: +1800xxxxxxx CallTime: 0:12 Cost: FREE
    2010-04-16 14:47:52,531 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SkypeUA - Qualified PSTN calls today: 1 Time: 12 minutes
    2010-04-16 14:47:52,531 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SSCallChannel.#C0 - AcctBalance: 0.00 USD
    2010-04-16 14:47:52,531 [org.zoolu.net.UdpProvider.T1] INFO  local.ua.SkypeUserAgent.#C0 - WAITING FOR INCOMING CALL
    2010-04-16 14:47:52,546 [AsyncSkypeMessageSender-101] INFO  local.ua.SSCallChannel.#C0 - skypeCallStatus[430861] - Complete: FINISHED
    
    3CXMediaServer.trace.log
    Code:
    14:47:32.125|.\MSEndPoint.cpp(760)|Trace5||??:00000E69@: RTP bind address 192.168.1.4:7010
    14:47:32.125|.\MSEndPoint.cpp(771)|Trace5||??:00000E69@: RTCP bind address 192.168.1.4:7011
    14:47:32.125|.\MediaServer.cpp(815)|Trace5||??:EndPoint created: (destination=192.168.1.2)
    EndPoint: ID=00000E69@(LOCAL)
      LOGID=C:1700.1  Status: MSEP_LOCAL
      RTP:192.168.1.4:7010
      RTCP:192.168.1.4:7011
      STUN RTP:0.0.0.0:0
      STUN RTCP:0.0.0.0:0
      Coder:
        NOT SET
        101:telephony-event
        Party ptime:20
      Party RTP:0.0.0.0:0
      Party RTCP:0.0.0.0:0
      Decoders:
        <empty>
    
    14:47:32.125|.\MediaServer.cpp(1260)|Trace5||??:EP 00000E69@ joined to call 1700
    14:47:32.125|.\MediaServer.cpp(1269)|Trace5||??:starting send on new call 1700
    14:47:32.140|.\MediaServer.cpp(912)|Trace5||??:Set Party EP:C:1700.1SDP:v=0
    o=- 8 2 IN IP4 192.168.1.2
    s=CounterPath eyeBeam 1.5
    c=IN IP4 192.168.1.2
    t=0 0
    m=audio 29986 RTP/AVP 107 100 106 6 0 105 18 3 5
    a=alt:1 1 : r26Acfau Omlv4xaW 192.168.1.2 29986
    a=fmtp:18 annexb=no
    a=rtpmap:107 BV32/16000
    a=rtpmap:100 SPEEX/16000
    a=rtpmap:106 SPEEX-FEC/16000
    a=rtpmap:105 SPEEX-FEC/8000
    a=sendrecv
    a=x-rtp-session-id:F4FC685CAA0949D6B106E801FAC71AC7
    
    14:47:32.140|.\MSEndPoint.cpp(2949)|Trace5||??:New state00000E69@ SILENCE
    14:47:32.140|.\MSEndPoint.cpp(1691)|Trace5||??:partyAddrRTP_ is set to 192.168.1.2:29986
    14:47:32.140|.\MSEndPoint.cpp(1692)|Trace5||??:partyAddrRTCP_ is set to 192.168.1.2:29987
    14:47:32.140|.\MSEndPoint.cpp(1914)|Log2||??:[MS210000] C:1700.1:Offer received. RTP connection: 192.168.1.2:29986(29987)
    14:47:32.187|.\MSEndPoint.cpp(760)|Trace5||??:00000E6A@: RTP bind address 127.0.0.1:7012
    14:47:32.187|.\MSEndPoint.cpp(771)|Trace5||??:00000E6A@: RTCP bind address 127.0.0.1:7013
    14:47:32.187|.\MediaServer.cpp(815)|Trace5||??:EndPoint created: (destination=192.168.1.4)
    EndPoint: ID=00000E6A@(LOCAL)
      LOGID=C:1700.2  Status: MSEP_LOCAL
      RTP:127.0.0.1:7012
      RTCP:127.0.0.1:7013
      STUN RTP:0.0.0.0:0
      STUN RTCP:0.0.0.0:0
      Coder:
        NOT SET
        101:telephony-event
        Party ptime:20
      Party RTP:0.0.0.0:0
      Party RTCP:0.0.0.0:0
      Decoders:
        <empty>
    
    14:47:32.187|.\MediaServer.cpp(1260)|Trace5||??:EP 00000E6A@ joined to call 1700
    14:47:32.187|.\MediaServer.cpp(1305)|Trace5||??:EndPoint 00000E6A@ removed from call 1700
    14:47:32.187|.\MediaServer.cpp(1260)|Trace5||??:EP 00000E6A@ joined to call 1700
    14:47:32.187|.\MSEndPoint.cpp(1403)|Log2||??:[MS210002] C:1700.2:Offer provided. Connection(transcoding mode): 127.0.0.1:7012(7013)
    14:47:32.187|.\MediaServer.cpp(850)|Trace5||??:Get Local SDP. EP:C:1700.2SDP:v=0
    o=3cxPS 516671143936 92509569025 IN IP4 127.0.0.1
    s=3cxPS Audio call
    c=IN IP4 127.0.0.1
    t=0 0
    m=audio 7012 RTP/AVP 0 8 3 99 13 18 110 101
    c=IN IP4 127.0.0.1
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:3 GSM/8000
    a=rtpmap:99 SPEEX/8000
    a=rtpmap:13 CN/8000
    a=rtpmap:18 G729/8000
    a=fmtp:18 annexb=no
    a=rtpmap:110 iLBC/8000
    a=rtpmap:101 telephone-event/8000
    a=sendrecv
    
    14:47:32.187|.\MediaServer.cpp(1305)|Trace5||??:EndPoint 00000E6A@ removed from call 1700
    14:47:38.093|.\MediaServer.cpp(912)|Trace5||??:Set Party EP:C:1700.2SDP:v=0
    o=3cxPS 516671143936 92509569025 IN IP4 127.0.0.1
    s=3cxPS Audio call
    c=IN IP4 192.168.1.4
    t=0 0
    m=audio 63206 RTP/AVP 0 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=sendrecv
    a=silenceSupp:off
    
    14:47:38.093|.\MSEndPoint.cpp(2949)|Trace5||??:New state00000E6A@ SILENCE
    14:47:38.093|.\MSEndPoint.cpp(1691)|Trace5||??:partyAddrRTP_ is set to 192.168.1.4:63206
    14:47:38.093|.\MSEndPoint.cpp(1692)|Trace5||??:partyAddrRTCP_ is set to 192.168.1.4:63207
    14:47:38.093|.\MSEndPoint.cpp(2949)|Trace5||??:New state00000E6A@ ACTIVE
    14:47:38.093|.\MSEndPoint.cpp(1919)|Log2||??:[MS210001] C:1700.2:Answer received. RTP connection[unsecure]: 192.168.1.4:63206(63207)
    14:47:38.093|.\MediaServer.cpp(1260)|Trace5||??:EP 00000E6A@ joined to call 1700
    14:47:38.093|.\MSEndPoint.cpp(2949)|Trace5||??:New state00000E69@ ACTIVE
    14:47:38.093|.\MSEndPoint.cpp(2949)|Trace5||??:New state00000E6A@ ACTIVE
    14:47:38.093|.\MSEndPoint.cpp(1408)|Log2||??:[MS210003] C:1700.1:Answer provided. Connection(transcoding mode[unsecure]):192.168.1.4:7010(7011)
    14:47:38.109|.\MediaServer.cpp(850)|Trace5||??:Get Local SDP. EP:C:1700.1SDP:v=0
    o=3cxPS 513282146304 350912249857 IN IP4 192.168.1.4
    s=3cxPS Audio call
    c=IN IP4 192.168.1.4
    t=0 0
    m=audio 7010 RTP/AVP 0 18 3 101
    c=IN IP4 192.168.1.4
    a=rtpmap:0 PCMU/8000
    a=rtpmap:18 G729/8000
    a=fmtp:18 annexb=no
    a=rtpmap:3 GSM/8000
    a=rtpmap:101 telephone-event/8000
    a=sendrecv
    
    14:47:38.203|.\MSEndPoint.cpp(2142)|Trace5||??:EP:00000E69@ refreshed
    14:47:43.078|.\MSEndPoint.cpp(2135)|Trace5||??:resynchronization. timediff:304
    14:47:43.078|.\MSEndPoint.cpp(2142)|Trace5||??:EP:00000E69@ refreshed
    14:47:48.968|.\MSEndPoint.cpp(2135)|Trace5||??:resynchronization. timediff:312
    14:47:48.968|.\MSEndPoint.cpp(2142)|Trace5||??:EP:00000E69@ refreshed
    14:47:52.281|.\MediaServer.cpp(1305)|Trace5||??:EndPoint 00000E69@ removed from call 1700
    14:47:52.281|.\MediaServer.cpp(1305)|Trace5||??:EndPoint 00000E6A@ removed from call 1700
    14:47:52.296|.\MediaServer.cpp(1065)|Trace5||??:references to EndPoint 00000E69@ were removed
    14:47:52.390|.\MediaServer.cpp(1065)|Trace5||??:references to EndPoint 00000E6A@ were removed
    14:47:52.390|.\MediaServer.cpp(1383)|Trace5||??:references to call 1700 were removed
    14:47:52.390|.\MSCallConf.cpp(36)|Trace5||??:Call: 1700 destroyed
    14:47:52.437|.\MSEndPoint.cpp(836)|Error1||??:[MS105000] C:1700.2: No RTP packets were received:remoteAddr=192.168.1.4:63206,extAddr=0.0.0.0:0,localAddr=127.0.0.1:7012
    14:47:52.437|.\MSEndPoint.cpp(847)|Trace5||??:EndPoint 00000E6A@ destroyed
    14:47:52.437|.\MSEndPoint.cpp(848)|Trace5||??:EndPoint 00000E6A@ destroyed statistics:
    Begin send:1048224734
    LastSent:1048238890
    TotalSent:708
    MaxSendInterval:47
    MinSendInterval:0
    AvgSendInterval:19.9944
    SendDeviation:8.65568
    Begin recv:0
    Last recv:0
    TotalRecv:0
    MaxRecvInterval:0
    MinRecvInterval:0
    AvgRecvInterval:0
    ReceiveDeviation:0
    
    14:47:53.734|.\MSEndPoint.cpp(847)|Trace5||??:EndPoint 00000E69@ destroyed
    14:47:53.734|.\MSEndPoint.cpp(848)|Trace5||??:EndPoint 00000E69@ destroyed statistics:
    Begin send:1048224734
    LastSent:1048238890
    TotalSent:708
    MaxSendInterval:32
    MinSendInterval:0
    AvgSendInterval:19.9943
    SendDeviation:8.3717
    Begin recv:1048224859
    Last recv:1048238828
    TotalRecv:694
    MaxRecvInterval:32
    MinRecvInterval:0
    AvgRecvInterval:20.1282
    ReceiveDeviation:7.13433
    
     
Thread Status:
Not open for further replies.