The file will play if it is restored then removed (mcp must use the cache to play it) however if i was to restart mcp the same problem occurs (assume restarting clears the cache?).
URS log:
[i]request to 65200(sipserver) message RequestApplyTreatment
AttributeReferenceID 106
AttributeExtensions [22] 00 01 00 00..
'geo-location' 'geo1'
AttributeTreatmentParms [60] 00 01 03 00..
'PROMPT'(list) '1'(list) 'INTERRUPTABLE' 0
'ID' 1111
AttributeTreatmentType 7 (TreatmentPlayAnnouncement)
AttributeConnID 00e90220c303c004
AttributeThisDN 'RP1'
..sent to [siphost]:3000(fd=724)
_B_I_00e90220c303c004 [14:28] treatment===========>TREATMENT_REQUEST
12:15:20.725_A_I_00e90220c303c004 [0E:0c] ----------->TMESSAGE
12:15:20.725_I_I_00e90220c303c004 [09:04] <<<<<<<<<<<<suspend interpretator(WAIT_PLAY_TREATMENT), func:TreatmentPlayAnnouncement timers:00000
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=99)
12:15:20.725_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=99)
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=100)
12:15:20.725_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=100)
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=101)
12:15:20.725_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=101)
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=102)
12:15:20.741_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=102)
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=103)
12:15:20.741_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=103)
received from 65200(sipserver)[siphost]:3000(fd=) message EventACK
AttributeUserEvent RequestDistributeEvent
AttributeThisDN 'exr'
AttributeTimeinSecs 1360239320 (12:15:20)
AttributeTimeinuSecs 758000
AttributeCustomerID 'Resources'
AttributeEventSequenceNumber 0000000000003b3f
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=104)
12:15:20.741_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=104)
received from 65200(sipserver)[siphost]:3000(fd=) message EventAttachedDataChanged(refid=105)
12:15:20.741_T_I_00e90220c303c004 [14:32] EventAttachedDataChanged is received for tserver sipserver[SIP] (this dn=RP1, refid=105)
12:15:22.007_M_I_ [10:1d] PULSE (calls: 1(1)=0+1-0, targets=0, time=1360239322, mem=0,47137,418,152,178,1)
12:15:22.007_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(0 0/1024)
12:15:24.007_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1360239324, mem=0,47137,418,152,178,1)
12:15:24.007_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(0 0/1024)
12:15:26.007_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1360239326, mem=0,47137,418,152,178,1)
12:15:26.007_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(0 0/1024)
12:15:28.007_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1360239328, mem=0,47137,418,152,178,1)
12:15:28.007_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(0 0/1024)
12:15:30.007_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1360239330, mem=0,47137,418,152,178,1)
12:15:30.007_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(0 0/1024)
received from 65200(sipserver)[siphost]:3000(fd=) message EventRouteUsed
AttributeCallState 22
AttributeCallType 2
AttributePropagatedCallType 2
AttributeCallID 4
AttributeConnID 00e90220c303c004
AttributeCallUUID 'BPDRUTEMVT665ALU9BP41P2RDG000004'
AttributeDNIS '111111'
AttributeANI '1111111111'
AttributeCustomerID 'Resources'
AttributeThisDN 'RP1'
AttributeThisDNRole 2
AttributeThisQueue 'RP1'
AttributeOtherQueue '111111'
AttributeOtherDN '1111111111'
AttributeOtherDNRole 1
AttributeThirdPartyDN '111111'
AttributeThirdPartyDNRole 2
AttributeExtensions [145] 00 04 01 00..
'MsmlResponseCode' 423
'ResponseDescription' 'External document fetch error: Failed to fetch file'
'OtherTrunkName' 'AVA'
'BusinessCall' 1
AttributeTimeinSecs 1360239331 (12:15:31)
AttributeTimeinuSecs 399000
AttributeEventSequenceNumber 0000000000003b48
12:15:31.366_T_I_00e90220c303c004 [14:0c] EventRouteUsed(strange) is received for tserver sipserver[SIP] (this dn=RP1)
_T_I_00e90220c303c004 [14:0a] del DN (sipserver[SIP] RP1) (ref.id=0)
_T_W_00e90220c303c004 [14:0a] there is no DNs for call, activate delayed selfdestruction
received from 65200(sipserver)[siphost]:3000(fd=) message EventDiverted[/i]
SIP log:
[i]--- Pre-event ---
+++ TreatmentError +++
@12:15:21.3980 [0] 8.1.001.15 distribute_event: message EventTreatmentNotApplied
AttributeEventSequenceNumber 0000000000003b45
AttributeTimeinuSecs 398000
AttributeTimeinSecs 1360239321 (12:15:21)
AttributeTreatmentParms [60] 00 01 03 00..
'PROMPT'(list) '1'(list) 'INTERRUPTABLE' 0
'ID' 1111
AttributeExtensions [145] 00 04 01 00..
'MsmlResponseCode' 423
'ResponseDescription' 'External document fetch error: Failed to fetch file'
'OtherTrunkName' 'AVA'
'BusinessCall' 1
AttributeErrorCode 50
AttributeErrorMessage 'Unspecified error'
AttributeTreatmentType 7 (TreatmentPlayAnnouncement)
AttributeThisQueue '111111'
AttributeThisDNRole 2
AttributeThisDN '111111'
AttributeCustomerID 'Resources'
AttributeANI '1111111111'
AttributeDNIS '111111'
AttributeCallUUID 'BPDRUTEMVT665ALU9BP41P2RDG000004'
AttributeConnID 00e90220c303c004
AttributeCallID 4
AttributePropagatedCallType 2
AttributeCallType 2
AttributeCallState 0
12:15:21.398 Int 04544 Interaction message "EventTreatmentNotApplied" generated
--- TreatmentError ---
+++ Post-event +++
Type EventTreatmentNotApplied
Devices: <111111/111111> <-/-> <-/->
Calls: 4/00e90220c303c004/4.2a7be38/c:2/r:1 0/none
Parties: D111111/111111.35e8ee8-2a7be38:1/l:2/r:0/Queued,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 ---
12:15:21.398 --- CIFace::Event ---
12:15:21.398: Max recovery Attempts exceeded, Treatment will be stopped
12:15:21.398: SIPTR(65): complete
12:15:21.398: SIPTR(63): Step 1 - SipTransactionProcessMsml(65) complete
12:15:21.398: SIPTR(63): complete
12:15:21.398: SIPCM: transaction SipScenario(63) complete
12:15:21.398: PI: 00 S[IN]D[1111111111]C[*D[1111111111]]P[msml_geo1]
12:15:21.398: PI: 00 S[QN]D[111111]E[-]MS[C[*D[msml_geo1]]]P[1111111111]
12:15:21.398: call1 4 idle
12:15:21.398: $-NET:SIP::0:1984[/i]