Jigasi Ignoring Codecs

I’m having a major issue getting actual phones to work with Jigasi. I have it communicating with my PBX over SIP and if I dial my holdmusic extension…I get music. I have to manually kill the channel since kicking the user doesn’t hangup…but that’s not my issue.

My issue is for some reason…it refuses to work with an actual phone. My deskphone has been configured to use only ulaw. My PBX configuration is set to only allow ulaw. But every time I make a call:

2022-08-04 18:21:47.363 SEVERE: [185] net.sf.fmj.media.Log.error: Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed

Why…WHY? Why are we even attempting to use 48khz. Is there something in sip-communicator.properties or some other config file I’m missing? Everything except ulaw is set to 0. But it still REFUES to work.

Okay…it appears to be an issue with just the phones. Here’s a call I made to an extension that goes straight to musiconhold:

2022-08-04 18:48:21.493 INFO: [68] CallControl.handleDialIq#201: [ctx=16596389014931738240592] Got dial request fromnumber -> 4653 room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:48:21.494 INFO: [68] JvbConference.start#479: [ctx=16596389014931738240592] Starting JVB conference room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:48:21.495 INFO: [68] JvbConference.createAccountPropertiesForCallId#1584: [ctx=16596389014931738240592] Using bosh url:https://lidnet.pickmy.org/http-bind?room=cheerfulcontactsretiretoday
2022-08-04 18:48:21.500 INFO: [68] JvbConference.setXmppProvider#615: [ctx=16596389014931738240592] Using ProtocolProviderServiceJabberImpl(Jabber:lidnet@lidnet.pickmy.org/77789ab0)
2022-08-04 18:48:21.508 INFO: [243] org.igniterealtime.jbosh.BOSHClient.init: Starting with 1 request processors
2022-08-04 18:48:21.533 INFO: [243] net.java.sip.communicator.impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged: Jingle : ON
2022-08-04 18:48:21.534 INFO: [243] JvbConference.registrationStateChanged#677: [ctx=16596389014931738240592] Registering XMPP.
2022-08-04 18:48:21.555 INFO: [243] net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl$JabberConnectionListener.authenticated: Authenticated: false
2022-08-04 18:48:21.556 INFO: [243] AudioModeration.xmppProviderRegistered#499: [ctx=16596389014931738240592] Discovered avmoderation.lidnet.pickmy.org for 0ms.
2022-08-04 18:48:21.556 INFO: [243] JvbConference.joinConferenceRoom#759: [ctx=16596389014931738240592] Joining JVB conference room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:48:21.593 SEVERE: [243] JvbConference.inviteFocus#1697: [ctx=16596389014931738240592] Could not invite the focus to the conference
org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from focus.lidnet.pickmy.org: XMPPError: not-acceptable - modify
        at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:171)
        at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:165)
        at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:284)
        at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:228)
        at org.jitsi.jigasi.JvbConference.inviteFocus(JvbConference.java:1691)
        at org.jitsi.jigasi.JvbConference.joinConferenceRoom(JvbConference.java:834)
        at org.jitsi.jigasi.JvbConference.registrationStateChanged(JvbConference.java:658)
        at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:185)
        at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:139)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:1395)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:968)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.initializeConnectAndLogin(ProtocolProviderServiceJabberImpl.java:793)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.register(ProtocolProviderServiceJabberImpl.java:495)
        at org.jitsi.jigasi.util.RegisterThread.run(RegisterThread.java:59)
2022-08-04 18:48:21.607 INFO: [253] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/focus has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:48:21.611 INFO: [83] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/7bccabdb has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:48:21.617 INFO: [225] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/77789ab0 has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:48:21.618 SEVERE: [243] JvbConference.registrationStateChanged#666: [ctx=16596389014931738240592] Registered bosh sid: d178dbee-7389-4976-94ba-7b9ca52a1a81
2022-08-04 18:48:21.771 INFO: [258] net.java.sip.communicator.impl.protocol.jabber.IceUdpTransportManager.createIceAgent: End gathering harvester within 13 ms
2022-08-04 18:48:21.802 INFO: [258] net.java.sip.communicator.impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates: End candidate harvest within 30 ms
2022-08-04 18:48:21.805 INFO: [258] JvbConference$JvbCallListener.incomingCallReceived#1337: [ctx=16596389014931738240592] Got invite from focus
2022-08-04 18:48:21.806 INFO: [258] StatsHandler.getStatsServiceWrapper#277: [ctx=16596389014931738240592] Jitsi-stats library initializing for account: Jabber:jigasi@auth.lidnet.pickmy.org
2022-08-04 18:48:21.806 WARNING: [258] StatsServiceFactory.createStatsService#94: KeyID/keyPath missing, will try using appSecret
2022-08-04 18:48:21.806 SEVERE: [258] StatsHandler$StatsServiceInitListener.error#396: [ctx=16596389014931738240592] Jitsi-stats library failed to initialize with reason: Missing parameres and error message: appSecret missing
2022-08-04 18:48:21.806 WARNING: [258] StatsServiceFactory.createStatsService#100: appSecret missing. Skipping callstats init
2022-08-04 18:48:21.812 INFO: [258] SipGatewaySession.onConferenceCallStarted#639: [ctx=16596389014931738240592] Created outgoing call to SipGatewaySession{sipCall=Call: id=16596389018071791610629 peers=1, destination='4653'}
2022-08-04 18:48:21.820 INFO: [267] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16596389014931738240592] SIP peer state: Connecting
2022-08-04 18:48:21.823 INFO: [268] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 96=rtpmap:-1 opus/48000/2 fmtp:usedtx=1; 102=rtpmap:-1 iLBC/8000; 97=rtpmap:-1 SILK/24000; 98=rtpmap:-1 SILK/16000; 99=rtpmap:-1 speex/32000; 100=rtpmap:-1 speex/16000; 103=rtpmap:-1 speex/8000;
2022-08-04 18:48:21.823 INFO: [268] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides []
2022-08-04 18:48:21.824 INFO: [268] SipGatewaySession$SipCallStateListener.handleCallState#1428: [ctx=16596389014931738240592] Sip call IN_PROGRESS: Call: id=16596389018071791610629 peers=1
2022-08-04 18:48:21.824 INFO: [268] SipGatewaySession$SipCallStateListener.handleCallState#1430: [ctx=16596389014931738240592] SIP call format used: rtpmap:0 PCMU/8000
2022-08-04 18:48:21.824 INFO: [268] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16596389014931738240592] SIP peer state: Connected
2022-08-04 18:48:21.826 INFO: [268] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:48:21.884 INFO: [268] net.java.sip.communicator.service.protocol.media.TransportManager.sendHolePunchPacket: Send NAT hole punch packets
2022-08-04 18:48:21.885 INFO: [102] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 unknown/90000;
2022-08-04 18:48:21.885 INFO: [102] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides [103->104 ]
2022-08-04 18:48:21.894 INFO: [102] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:48:21.962 SEVERE: [302] RTPConnectorInputStream.runInReceiveThread#802: Failed to receive a packet:
java.lang.IllegalArgumentException: 'waitMillis' cannot be negative
        at org.bouncycastle.tls.DTLSTransport.receive(Unknown Source)
        at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.reverseTransformDtls(DtlsPacketTransformer.java:901)
        at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.transformDtls(DtlsPacketTransformer.java:1367)
        at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.transform(DtlsPacketTransformer.java:1313)
        at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.reverseTransform(DtlsPacketTransformer.java:831)
        at org.jitsi.impl.neomedia.transform.TransformEngineChain$PacketTransformerChain.reverseTransform(TransformEngineChain.java:381)
        at org.jitsi.impl.neomedia.transform.TransformInputStream.createRawPacket(TransformInputStream.java:86)
        at org.jitsi.impl.neomedia.RTPConnectorInputStream.runInReceiveThread(RTPConnectorInputStream.java:793)
        at java.base/java.lang.Thread.run(Thread.java:829)
2022-08-04 18:48:22.045 INFO: [102] JvbConference$JvbCallChangeListener.callStateChanged#1440: [ctx=16596389014931738240592] JVB conference call IN_PROGRESS.
2022-08-04 18:48:22.045 WARNING: [102] StatsHandler.startConferencePeriodicRunnable#330: [ctx=16596389014931738240592] Stats handler missing for call:Call: id=16596389017461867886055 peers=1
2022-08-04 18:48:22.051 SEVERE: [337] net.sf.fmj.media.Log.error:   Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
2022-08-04 18:48:22.051 SEVERE: [337] net.sf.fmj.media.Log.error: Failed to prefetch: net.sf.fmj.media.ProcessEngine@39407216
2022-08-04 18:48:22.058 SEVERE: [336] net.sf.fmj.media.Log.error: Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@39407216

I get the same error…but I do see where it uses ulaw and the call completes. My hold music joins the conference. Now here’s what happens when I call my deskphone:

2022-08-04 18:50:25.538 INFO: [68] CallControl.handleDialIq#201: [ctx=16596390255371767674067] Got dial request fromnumber -> 6420 room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:50:25.538 INFO: [68] JvbConference.start#479: [ctx=16596390255371767674067] Starting JVB conference room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:50:25.539 INFO: [68] JvbConference.createAccountPropertiesForCallId#1584: [ctx=16596390255371767674067] Using bosh url:https://lidnet.pickmy.org/http-bind?room=cheerfulcontactsretiretoday
2022-08-04 18:50:25.544 INFO: [68] JvbConference.setXmppProvider#615: [ctx=16596390255371767674067] Using ProtocolProviderServiceJabberImpl(Jabber:lidnet@lidnet.pickmy.org/5a941cae)
2022-08-04 18:50:25.549 INFO: [384] org.igniterealtime.jbosh.BOSHClient.init: Starting with 1 request processors
2022-08-04 18:50:25.568 INFO: [384] net.java.sip.communicator.impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged: Jingle : ON
2022-08-04 18:50:25.568 INFO: [384] JvbConference.registrationStateChanged#677: [ctx=16596390255371767674067] Registering XMPP.
2022-08-04 18:50:25.586 INFO: [384] net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl$JabberConnectionListener.authenticated: Authenticated: false
2022-08-04 18:50:25.587 INFO: [384] AudioModeration.xmppProviderRegistered#499: [ctx=16596390255371767674067] Discovered avmoderation.lidnet.pickmy.org for 0ms.
2022-08-04 18:50:25.587 INFO: [384] JvbConference.joinConferenceRoom#759: [ctx=16596390255371767674067] Joining JVB conference room: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
2022-08-04 18:50:25.606 SEVERE: [384] JvbConference.inviteFocus#1697: [ctx=16596390255371767674067] Could not invite the focus to the conference
org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from focus.lidnet.pickmy.org: XMPPError: not-acceptable - modify
        at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:171)
        at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:165)
        at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:284)
        at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:228)
        at org.jitsi.jigasi.JvbConference.inviteFocus(JvbConference.java:1691)
        at org.jitsi.jigasi.JvbConference.joinConferenceRoom(JvbConference.java:834)
        at org.jitsi.jigasi.JvbConference.registrationStateChanged(JvbConference.java:658)
        at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:185)
        at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:139)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:1395)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:968)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.initializeConnectAndLogin(ProtocolProviderServiceJabberImpl.java:793)
        at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.register(ProtocolProviderServiceJabberImpl.java:495)
        at org.jitsi.jigasi.util.RegisterThread.run(RegisterThread.java:59)
2022-08-04 18:50:25.637 INFO: [395] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/77789ab0 has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:25.654 INFO: [395] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/7bccabdb has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:25.659 INFO: [395] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/focus has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:25.660 INFO: [257] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/5a941cae has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:25.666 SEVERE: [384] JvbConference.registrationStateChanged#666: [ctx=16596390255371767674067] Registered bosh sid: 3ddc3bc6-c575-4e69-92f0-5f9a120bb5e0
2022-08-04 18:50:25.677 INFO: [398] net.java.sip.communicator.impl.protocol.jabber.IceUdpTransportManager.createIceAgent: End gathering harvester within 6 ms
2022-08-04 18:50:25.710 INFO: [392] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.joined: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/5a941cae has joined the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:25.723 INFO: [398] net.java.sip.communicator.impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates: End candidate harvest within 46 ms
2022-08-04 18:50:25.726 INFO: [398] JvbConference$JvbCallListener.incomingCallReceived#1337: [ctx=16596390255371767674067] Got invite from focus
2022-08-04 18:50:25.726 INFO: [398] StatsHandler.getStatsServiceWrapper#277: [ctx=16596390255371767674067] Jitsi-stats library initializing for account: Jabber:jigasi@auth.lidnet.pickmy.org
2022-08-04 18:50:25.726 WARNING: [398] StatsServiceFactory.createStatsService#94: KeyID/keyPath missing, will try using appSecret
2022-08-04 18:50:25.726 SEVERE: [398] StatsHandler$StatsServiceInitListener.error#396: [ctx=16596390255371767674067] Jitsi-stats library failed to initialize with reason: Missing parameres and error message: appSecret missing
2022-08-04 18:50:25.726 WARNING: [398] StatsServiceFactory.createStatsService#100: appSecret missing. Skipping callstats init
2022-08-04 18:50:25.730 INFO: [398] SipGatewaySession.onConferenceCallStarted#639: [ctx=16596390255371767674067] Created outgoing call to SipGatewaySession{sipCall=Call: id=16596390257261666222822 peers=1, destination='6420'}
2022-08-04 18:50:25.744 INFO: [406] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16596390255371767674067] SIP peer state: Connecting
2022-08-04 18:50:25.771 INFO: [407] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 96=rtpmap:-1 opus/48000/2 fmtp:usedtx=1; 102=rtpmap:-1 iLBC/8000; 97=rtpmap:-1 SILK/24000; 98=rtpmap:-1 SILK/16000; 99=rtpmap:-1 speex/32000; 100=rtpmap:-1 speex/16000; 103=rtpmap:-1 speex/8000;
2022-08-04 18:50:25.772 INFO: [407] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides []
2022-08-04 18:50:25.773 INFO: [407] SipGatewaySession$SipCallStateListener.handleCallState#1428: [ctx=16596390255371767674067] Sip call IN_PROGRESS: Call: id=16596390257261666222822 peers=1
2022-08-04 18:50:25.773 INFO: [407] SipGatewaySession$SipCallStateListener.handleCallState#1430: [ctx=16596390255371767674067] SIP call format used: rtpmap:0 PCMU/8000
2022-08-04 18:50:25.774 INFO: [407] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16596390255371767674067] SIP peer state: Connected
2022-08-04 18:50:25.775 INFO: [407] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:50:25.794 INFO: [102] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 unknown/90000;
2022-08-04 18:50:25.794 INFO: [102] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides [103->104 ]
2022-08-04 18:50:25.800 INFO: [102] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:50:25.913 INFO: [407] net.java.sip.communicator.service.protocol.media.TransportManager.sendHolePunchPacket: Send NAT hole punch packets
2022-08-04 18:50:25.957 INFO: [102] JvbConference$JvbCallChangeListener.callStateChanged#1440: [ctx=16596390255371767674067] JVB conference call IN_PROGRESS.
2022-08-04 18:50:25.957 WARNING: [102] StatsHandler.startConferencePeriodicRunnable#330: [ctx=16596390255371767674067] Stats handler missing for call:Call: id=1659639025669632699950 peers=1
2022-08-04 18:50:25.983 SEVERE: [485] net.sf.fmj.media.Log.error:   Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
2022-08-04 18:50:25.983 SEVERE: [485] net.sf.fmj.media.Log.error: Failed to prefetch: net.sf.fmj.media.ProcessEngine@ce1e679
2022-08-04 18:50:25.984 SEVERE: [484] net.sf.fmj.media.Log.error: Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@ce1e679

2022-08-04 18:50:27.914 INFO: [539] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 96=rtpmap:-1 opus/48000/2 fmtp:usedtx=1; 102=rtpmap:-1 iLBC/8000; 97=rtpmap:-1 SILK/24000; 98=rtpmap:-1 SILK/16000; 99=rtpmap:-1 speex/32000; 100=rtpmap:-1 speex/16000; 103=rtpmap:-1 speex/8000;
2022-08-04 18:50:27.915 INFO: [539] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides []
2022-08-04 18:50:27.917 INFO: [540] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:50:27.931 SEVERE: [410] RTPConnectorOutputStream.send#483: Failed to send a packet to target 192.168.1.156/192.168.1.156:10150:java.io.IOException: Invalid argument
2022-08-04 18:50:27.954 SEVERE: [410] RTPConnectorOutputStream.send#483: Failed to send a packet to target 192.168.1.156/192.168.1.156:10150:java.io.IOException: Invalid argument
2022-08-04 18:50:27.971 SEVERE: [410] RTPConnectorOutputStream.send#483: Failed to send a packet to target 192.168.1.156/192.168.1.156:10150:java.io.IOException: Invalid argument

[.....every 20ms while phone is connected]

2022-08-04 18:50:38.851 SEVERE: [410] RTPConnectorOutputStream.send#483: Failed to send a packet to target 192.168.1.156/192.168.1.156:10150:java.io.IOException: Invalid argument
2022-08-04 18:50:38.854 INFO: [541] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 96=rtpmap:-1 opus/48000/2 fmtp:usedtx=1; 102=rtpmap:-1 iLBC/8000; 97=rtpmap:-1 SILK/24000; 98=rtpmap:-1 SILK/16000; 99=rtpmap:-1 speex/32000; 100=rtpmap:-1 speex/16000; 103=rtpmap:-1 speex/8000;
2022-08-04 18:50:38.854 INFO: [541] net.java.sip.communicator.service.protocol.media.MediaHandler.registerDynamicPTsWithStream: PT overrides []
2022-08-04 18:50:38.862 INFO: [542] net.java.sip.communicator.service.protocol.media.CallPeerMediaHandler.start: Starting
2022-08-04 18:50:38.868 INFO: [543] SipGatewaySession$SipCallStateListener.handleCallState#1440: [ctx=16596390255371767674067] SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.CallPeerState:Connected newV=net.java.sip.communicator.service.protocol.CallPeerState:Disconnected for peer=6420 <6420@localhost>;status=Disconnected
2022-08-04 18:50:38.869 INFO: [543] SipGatewaySession$CallPeerListener.peerStateChanged#1501: [ctx=16596390255371767674067] SIP peer state: Disconnected
2022-08-04 18:50:43.870 INFO: [544] SipGatewaySession.sipCallEnded#694: [ctx=16596390255371767674067] Sip call ended: Call: id=16596390257261666222822 peers=0
2022-08-04 18:50:43.887 INFO: [396] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$MemberListener.left: cheerfulcontactsretiretoday@conference.lidnet.pickmy.org/5a941cae has left the cheerfulcontactsretiretoday@conference.lidnet.pickmy.org chat room.
2022-08-04 18:50:43.888 INFO: [396] JvbConference.memberPresenceChanged#1151: [ctx=16596389014931738240592] Member left : MEMBER lidnet@lidnet.pickmy.org/5a941cae
2022-08-04 18:50:43.893 INFO: [544] JvbConference.memberPresenceChanged#1151: [ctx=16596390255371767674067] Member left : OWNER focus@auth.lidnet.pickmy.org/focus
2022-08-04 18:50:43.896 WARNING: [395] net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl.leave: Error occured while leaving, maybe just disconnected before leaving
org.jivesoftware.smackx.muc.MultiUserChatException$MucNotJoinedException: Client not currently joined cheerfulcontactsretiretoday@conference.lidnet.pickmy.org
        at org.jivesoftware.smackx.muc.MultiUserChat.leave(MultiUserChat.java:769)
        at net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl.leave(ChatRoomJabberImpl.java:1056)
        at net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl.leave(ChatRoomJabberImpl.java:984)
        at net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$ChatRoomPresenceListener.processOwnPresence(ChatRoomJabberImpl.java:3398)
        at net.java.sip.communicator.impl.protocol.jabber.ChatRoomJabberImpl$ChatRoomPresenceListener.processStanza(ChatRoomJabberImpl.java:3320)
        at org.jivesoftware.smack.AbstractXMPPConnection$4.run(AbstractXMPPConnection.java:1601)
        at org.jivesoftware.smack.AbstractXMPPConnection$10.run(AbstractXMPPConnection.java:2146)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
        at java.base/java.lang.Thread.run(Thread.java:829)
2022-08-04 18:50:43.904 INFO: [544] JvbConference.memberPresenceChanged#1151: [ctx=16596390255371767674067] Member left : MEMBER lidnet@lidnet.pickmy.org
2022-08-04 18:50:43.911 INFO: [544] JvbConference.memberPresenceChanged#1151: [ctx=16596390255371767674067] Member left : MEMBER lidnet@lidnet.pickmy.org/77789ab0
2022-08-04 18:50:43.917 INFO: [544] JvbConference.memberPresenceChanged#1151: [ctx=16596390255371767674067] Member left : OWNER ikcrcu177pu3obtu@lids.lidnet.pickmy.org/KdH9Rarb
2022-08-04 18:50:43.926 INFO: [544] AbstractGateway.notifyCallEnded#129: [ctx=16596390255371767674067] Removed session for call. Sessions:1
2022-08-04 18:50:43.952 SEVERE: [416] RTPConnectorOutputStream.send#483: Failed to send a packet to target /2600:4040:2e40:d300:7e83:34ff:feb2:cb8a:10000:java.io.IOException: No active socket.
2022-08-04 18:50:43.952 SEVERE: [102] RTPConnectorOutputStream.send#483: Failed to send a packet to target /2600:4040:2e40:d300:7e83:34ff:feb2:cb8a:10000:java.io.IOException: No active socket.
2022-08-04 18:50:43.964 INFO: [102] net.java.sip.communicator.impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged: Jingle : OFF
2022-08-04 18:50:43.965 INFO: [102] CallManager$HangupCallThread.run#660: [ctx=16596390255371767674067] Removing account Jabber:lidnet@lidnet.pickmy.org/5a941cae
2022-08-04 18:50:43.965 INFO: [102] net.java.sip.communicator.impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged: Jingle : OFF

The problem is I don’t know were to get much more debugging information. Asterisk only shows normal call activity. My deskphone’s menu indicates the previous call was ulaw. So this isn’t a codec issue…but the audio just doesn’t move.

@damencho any ideas?

The only think I can think of is if music on hold works, this is jigasi to asterisk. With the phone probably that reinvites or tries to establish direct rtp connection between phone and jigasi, make sure you configure it so the RTP goes through asterisk, there were some options for that.

canreinvite=no.
nat=force_rport,comedia 

AHHHH. Okay. The way Asterisk is it tries to hand everything off directly via RTP.

I will give that a try. I had to reinstall the OS on the machine I was using for labbing and I have unrelated issues…like it’s asking everyone for auth and not just the host of the room.

Yes. It was the Direct RTP function that was causing the issues.

direct_media=no

canreinvite is the old SIP option; that’s been depreciated in Asterisk in favor of PJSIP.