Thanks everyone for helping. I tried the suggestions and still have no music.
[quote author=cavagnaro link=topic=12399.msg55198#msg55198 date=1676637258]
What about the LRG to define the services, record, music, vxml, etc?
[/quote]
Yes, the LRG has a MCP Group Type, and all services are checked : VoiceXML, Conference, Announcement, Recording Client, CPD, Media, MSML and Treatment. I also have a VOIP Service DN named "Treatment" with the contact and service-type="treatment" options.
[quote author=hsujdik link=topic=12399.msg55199#msg55199 date=1676640173]
This is kind of strange. By only configuring the MSML VoIP Server, you should at least see SIP Server attempting to send a message that looks like this one to your Resource Manager:
[/quote]
I had the Subscription and even Notify requests, but this time I have the "Environment/10.0.243.198:5070/in-service". But I have a new "no service found" in logs :
09:55:36.741 Trc 04541 RequestApplyTreatment received from [1160] (00000005 urs 10.0.241.8:65325)
message RequestApplyTreatment
AttributeThisDN '3199996'
AttributeConnID 0097034ecd488001
AttributeTreatmentType 2 (TreatmentMusic)
AttributeTreatmentParms [56] 00 02 00 00..
'MUSIC_DN' 'PRO_MUSIC_2018.wav'
'DURATION' 900
AttributeReferenceID 368
09:55:36.741 Int 04543 Interaction message "RequestApplyTreatment" received from 1160 ("urs")
09:55:36.741 -- created: CRequest@9d9a2ea0 RequestApplyTreatment-urs[1160]/368
09:55:36.741: $+TLIB:CTI:Unknown:0:14
09:55:36.741 +++ CIFace::Request +++
-- new invoke
-- thisCall by party
Parsed: RequestApplyTreatment
From: urs[1160]/368
Numbers: +<3199996> -<none>
Calls: 9d84fab0:1 none
Parties: 3199996.9ef415a0-9d84fab0:1
none
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
-- validate
-- state check: ok
CIFace: Sent CRequest@9d9a2ea0 RequestApplyTreatment-urs[1160]/368
FinishRequest CRequest@9d9a2ea0 RequestApplyTreatment-urs[1160]/368
IFace stats: q=0 s=0
-- complete
09:55:36.741: RID:CUUID>368:OVRRGMKFIP4J59P5N74MAGF7N0000001:
09:55:36.741: Selected for Dn (geo-loc[VAL]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service msml (geo-loc[], priority[0], capacity 0 (0% of 0))
09:55:36.745 SIPCONN(msml): set monitor 000000009ef9d190, 000000009ef637b0
09:55:36.753: Sc(6):step 0, Tr(7,SipTransactionConnectMediaService) - begin
09:55:36.753 SIPCONN(anonymous): re-invite-called-initiated
09:55:36.753 SIPCONN(anonymous): SIPCONN(000000009d84f1a0,I4J28575B50IH25P5U48E8O2R0000001) +Tr(7,SipTransactionConnectMediaService)
09:55:36.753 SIPCONN(msml): re-invite-null
09:55:36.753 SIPCONN(msml): SIPCONN(000000009ef9d190,I4J28575B50IH25P5U48E8O2R0000002) +Tr(7,SipTransactionConnectMediaService)
09:55:36.753 SIPCONN(anonymous): GetOffer
09:55:36.753 SIPCONN(anonymous): GetOffer::ReturnOffer
09:55:36.753 SIPCONN(anonymous): NotifyOnOffer
09:55:36.753 SIPCONN(msml): SendOffer
09:55:36.753 SIPCONN(msml): re-invite-null
09:55:36.753 SIPCONN(msml): SendOffer::Invite
09:55:36.753: SipDialog: set monitor 000000009ef9d248
09:55:36.753 SIPCONN(msml): main dialog 0 created, flags 0x21f
Session value of the SDP is [1676883336]
Version values of the SDP is [1]
09:55:36.753 SIPCONN(msml): Local contact: '<sip:anonymous@10.0.243.198:5060>'
09:55:36.753: SIPDLG[7]: register TRN[329]
09:55:36.753: SIPDLG[7]: TRN[329] flags set to 0x6
09:55:36.753: Sending [0,UDP] 1586 bytes to 10.0.243.198:5160 >>>>>
INVITE sip:msml@10.0.243.198:5160;tenant-dbid=1;media-service=treatment SIP/2.0
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=4B302DFA-6A0E-4F85-9235-54CCFEEDD4D9-39
To: <sip:msml@10.0.243.198:5060>
Call-ID: 610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198
CSeq: 1 INVITE
Content-Length: 197
Content-Type: application/sdp
Via: SIP/2.0/UDP 10.0.243.198:5060;branch=z9hG4bKD4FF603F-DCD3-48E5-A76C-3128E6A303A5-5
Contact: <sip:anonymous@10.0.243.198:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
Max-Breadth: 60
P-Charging-Vector: icid-value="AAS:9840-be7da757ed41fcb050004ea7423ca156"
P-Asserted-Identity: "qng" <sip:sip.pro>
History-Info: <sip:3199996@sip.pro>;index=1
History-Info: "3199996" <sip:3199996@sip.pro>;index=1.1
Alert-Info: <cid:internal@sip.pro>;avaya-cm-alert-type=internal
Accept-Language: en
User-Agent: Avaya CM/R018x.01.0.890.0 AVAYA-SM-8.1.3.3.813310
P-AV-Message-Id: 3_1
Av-Global-Session-ID: 57a77d14-b0fc-41ed-a74d-005056a13c42
P-Location: SM;origlocname="PRO";origsiglocname="PRO";origmedialocname="PRO";termlocname="PRO";termsiglocname="PRO";smaccounting="true"
Max-Forwards: 65
X-Genesys-geo-location: VAL
X-Genesys-CallUUID: OVRRGMKFIP4J59P5N74MAGF7N0000001
Privacy: id
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,timer
v=0
o=- 1676883347 1 IN IP4 10.0.27.104
s=-
c=IN IP4 10.0.27.104
b=AS:64
t=0 0
a=avf:avc=n prio=n
a=csup:avf-v0
m=audio 6016 RTP/AVP 8 0 127
a=sendrecv
a=rtpmap:127 telephone-event/8000
09:55:36.753: SipDialog: event SEND_INVITE, t=329, s=2, r=6, m=000000009ef9d248 port=5160
09:55:36.753: CID:CUUID>610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198:OVRRGMKFIP4J59P5N74MAGF7N0000001:
09:55:36.753 SIPCONN(msml): HandleSipDialogEvent(SEND_INVITE) - filtered
09:55:36.753 SIPCONN(msml): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
09:55:36.753 SIPCONN(msml): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
09:55:36.753 --- CIFace::Request ---
09:55:36.753: $-TLIB:CTI:Unknown:0:14410
09:55:36.785: $+NET:SIP::0:0
09:55:36.785: SIPTR: Received [0,UDP] 517 bytes from 10.0.243.198:5160 <<<<<
SIP/2.0 603 Decline
Via: SIP/2.0/UDP 10.0.243.198:5060;branch=z9hG4bKD4FF603F-DCD3-48E5-A76C-3128E6A303A5-5
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=4B302DFA-6A0E-4F85-9235-54CCFEEDD4D9-39
To: <sip:msml@10.0.243.198:5060>;tag=AA7FABF5-7D42-4D89-9381-A578735F2FAD
CSeq: 1 INVITE
Call-ID: 610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198
Contact: sip:GVP@10.0.243.198:5160
Content-Length: 0
Warning: 399 10.0.243.198 "No matching resources for this service type [Service Type: treatment]"
09:55:36.785: Sending [0,UDP] 517 bytes to 10.0.243.198:5160 >>>>>
ACK sip:msml@10.0.243.198:5160;tenant-dbid=1;media-service=treatment SIP/2.0
Via: SIP/2.0/UDP 10.0.243.198:5060;branch=z9hG4bKD4FF603F-DCD3-48E5-A76C-3128E6A303A5-5
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=4B302DFA-6A0E-4F85-9235-54CCFEEDD4D9-39
To: <sip:msml@10.0.243.198:5060>;tag=AA7FABF5-7D42-4D89-9381-A578735F2FAD
Call-ID: 610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198
CSeq: 1 ACK
Max-Forwards: 65
User-Agent: Avaya CM/R018x.01.0.890.0 AVAYA-SM-8.1.3.3.813310
Content-Length: 0
09:55:36.785: SipDialog: event CALLING_RESREJECT, t=329, s=9, r=5, m=000000009ef9d248 port=5160
09:55:36.785: CID:CUUID>610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198:OVRRGMKFIP4J59P5N74MAGF7N0000001:
09:55:36.785 SIPCONN(msml): HandleSipDialogEvent(CALLING_RESREJECT)
09:55:36.785 SIPCONN(msml): Connect(2,2)
09:55:36.785 SIPCONN(msml): ConvertResponse: 603
09:55:36.785 SIPCONN(msml): ConvertResponse: no conversion configured for 603
09:55:36.785: SIPDM: locate new destination for failed device 'msml'
09:55:36.785: GetTheBestNode: no eligible service found for
09:55:36.785: REGISTRAR: Replacement cannot be found for service [msml], Dn
09:55:36.785: ERROR: 1000003d, GetProxyRegistrar().FindServiceReplacement(originalVoipService.CStr(), SIP_MEDIA_SERVICE_MSML, emptyString.CStr(), conferenceId.CStr(), result, overflow), SipDeviceManager.cpp,4088
09:55:36.785: ERROR: 1000003d, GetNewMsmlDestination(failedDevice,cause,sipresponse,newDeviceInfo,canReplaceService, overflow), SipDeviceManager.cpp,1511
09:55:36.785 SIPCONN(msml): state e:4,p:0,s:6,c:28,rc:603,m:1
09:55:36.785 SIPCONN(msml): NotifyOnError
09:55:36.785 SIPCONN(msml): TRCLR(329)
09:55:36.785 SIPCONN(anonymous): re-invite-called-initiated
09:55:36.785: ConnectAbort: state:2
09:55:36.785 SIPCONN(anonymous): SIPCONN(000000009d84f1a0,I4J28575B50IH25P5U48E8O2R0000001) -Tr(7,SipTransactionConnectMediaService)
09:55:36.785 SIPCONN(msml): SIPCONN(000000009ef9d190,I4J28575B50IH25P5U48E8O2R0000002) -Tr(7,SipTransactionConnectMediaService)
09:55:36.785 SIPCONN(anonymous): ClrMediaPeer
09:55:36.785 SIPCONN(msml): ClrMediaPeer
09:55:36.785: music service failed
09:55:36.789 SIPCONN(msml): ClrMediaPeer
09:55:36.789: SIPTS: HandleMediaServiceFailed
09:55:36.789: SIPTS: SendTreatmentApplied
09:55:36.789: Adding OtherTrunkName(Trunk_SIP_Avaya_VAL): done
09:55:36.789 -- thisCall by party
09:55:36.789 SetContext: for party 3199996.9ef415a0-9d84fab0:1
09:55:36.789 +++ CIFace::Event +++
+++ Pre-event +++
Type EventTreatmentNotApplied
Devices: <3199996/3199996> <-/-> <-/->
Calls: 16777217/0097034ecd488001/16777217.9d84fab0/c:2/r:1 0/none
Parties: D3199996/3199996.9ef415a0-9d84fab0:1/l:2/r:1/Queued,RtRequest,Destination
none
none
Cause: Null/0, Info: 50
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Pre-event ---
+++ TreatmentError +++
@09:55:36.7890 [0] 8.1.104.58 distribute_response: message EventTreatmentNotApplied
AttributeEventSequenceNumber 000000000000007a
AttributeTimeinuSecs 789000
AttributeTimeinSecs 1676883336 (09:55:36)
AttributeTreatmentParms [56] 00 02 00 00..
'MUSIC_DN' 'PRO_MUSIC_2018.wav'
'DURATION' 900
AttributeExtensions [197] 00 04 01 00..
'SipResponseCode' 603
'ResponseDescription' '399 10.0.243.198 "No matching resources for this service type [Service Type: treatment]"'
'OtherTrunkName' 'Trunk_SIP_Avaya_VAL'
'BusinessCall' 1
AttributeReferenceID 368
AttributeErrorCode 50
AttributeErrorMessage 'Unspecified error'
AttributeTreatmentType 2 (TreatmentMusic)
AttributePartyUUID '0T89NUTRMH5SJF6G451EA9VEQ0000005'
AttributeThisQueue '3199996'
AttributeThisDNRole 2
AttributeThisDN '3199996'
AttributeANI 'anonymous'
AttributeDNIS '3199996'
AttributeUserData [182] 00 05 00 00..
'IW_OverrideOptions' 'WDEVoix'
'UD_Origine' 'anonymous'
'UD_ConnId' '0097034ecd488001'
'UD_MUNA' '34393538303032352E3730'
'UD_COMET' 'http://localhost:9000?muna=34393538303032352E3730'
AttributeCallUUID 'OVRRGMKFIP4J59P5N74MAGF7N0000001'
AttributeConnID 0097034ecd488001
AttributeCallID 16777217
AttributePropagatedCallType 2
AttributeCallType 2
AttributeCallState 0
09:55:36.789 Int 04544 Interaction message "EventTreatmentNotApplied" generated
09:55:36.789 Trc 04542 EventTreatmentNotApplied sent to [1160] (00000005 urs 10.0.241.8:65325)
09:55:36.789 Trc 04542 EventTreatmentNotApplied sent to [968] (00000003 ors 10.0.241.5:64743)
09:55:36.789 Trc 04542 EventTreatmentNotApplied sent to [1156] (00000004 stat_server_routage 10.0.241.8:65324)
--- TreatmentError ---
+++ Post-event +++
Type EventTreatmentNotApplied
Devices: <3199996/3199996> <-/-> <-/->
Calls: 16777217/0097034ecd488001/16777217.9d84fab0/c:2/r:1 0/none
Parties: D3199996/3199996.9ef415a0-9d84fab0:1/l:2/r:1/Queued,RtRequest,Destination
none
none
Cause: Null/0, Info: 50
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Post-event ---
09:55:36.789 --- CIFace::Event ---
09:55:36.789 SIPCONN(msml): state e:1,p:6,s:0,c:0,rc:0,m:1
09:55:36.789: SipDialog: ClearCall(phone=0,state=9, reject[set:0;conf:603])
09:55:36.789: SipDialog::Terminate(state=9,reason=0)
09:55:36.789: SipDialog: set monitor 0000000000000000
09:55:36.789 SIPCONN(msml): DetachMediaPeer
09:55:36.789 SIPCONN(anonymous): re-invite-called-initiated
09:55:36.789 SIPCONN(anonymous): SIPCONN(000000009d84f1a0,I4J28575B50IH25P5U48E8O2R0000001) +Tr(7,SipTransactionConnectMediaService)
09:55:36.789 SIPCONN(anonymous): park-initiated
09:55:36.789 SIPCONN(anonymous): SIPCONN(000000009d84f1a0,I4J28575B50IH25P5U48E8O2R0000001) -Tr(7,SipTransactionConnectMediaService)
09:55:36.789: MediaService type 3 does not have associated connection.
09:55:36.789: Tr(7,SipTransactionConnectMediaService):completed
09:55:36.789: Sc(6):step 0, Tr(7,SipTransactionConnectMediaService) - completed
09:55:36.789: Sc(6):step 1, Tr(8,SipTransactionProcessMsml) - begin
09:55:36.789: Sc(6):step 1, Tr(8,SipTransactionProcessMsml) - failed
09:55:36.789: Tr(6,SipScenario):failed
09:55:36.789: SIPCM: transaction Tr(6,SipScenario) failed
09:55:36.789: PI-1: 00 S[IN]D[anonymous]C[*D[anonymous]]P[-]
09:55:36.789: PI-1: 00 S[QN]D[3199996]E[-]
09:55:36.789: SipCallManager::OnTransactionFailure: Internal context, doesn't notify
09:55:36.789: OnRequestFailed
09:55:36.789 -- thisCall by party
09:55:36.789: call1 16777217 idle
09:55:36.789: SipDialog: event DESTROY, t=0, s=10, r=4, m=0000000000000000 port=5160
09:55:36.789: CID:CUUID>610FCF9B-ADE6-47BA-965A-0BD84332D41A-37@10.0.243.198:OVRRGMKFIP4J59P5N74MAGF7N0000001:
09:55:36.789: DIALOG_TRANSPORT_ASSOCIATION[7] NOT FOUND
09:55:36.789: SipDialog[7]:<< Abort ALL <<
09:55:36.789: $-NET:SIP::0:4418
[quote author=Kubig link=topic=12399.msg55201#msg55201 date=1676767107]
Try to check partition-id option configuration - I have encountered similiar issue and had to configure it properly on SIP app object and VoIP service DN object
[/quote]
Hi Kubig, I will look at it, thank you.
I don't know if it is linked or not, but from the begining, I have another strange issue in my SIP architecture : the WDE does not trigger RONA or ACW after inbound calls.