External Fetch Error

[PFCCWA]

Hello,

Has anyone experienced a problem whereby a missing announcement/file caused the following error:

[b][i]'ResponseDescription' 'External document fetch error: Failed to fetch file'[/i][/b]

We are using the latest sip release (8.1.001), and Media Control Platform (8.1.6).
The issue appears related to sip as it doesnt occur in 8.0.4.
If we add the missing announcement, then it works as expected but our issue is if someone accidently deletes it or renames incorrectly none of the callers can route to an agent - callers hear silence.

thanks.

[Kubig]

I have installed same version as you and the behavior is too same as your,but if the SIP server received this message (Fetch failed),generates EventTreatmentNotApplied and the strategy continue to the next block. What behavior do you expect or want?

[PFCCWA]

The problem is, the strategy does not continue to the next block, just stops and fails on the play announcement block - on sip 8.1 however on sip 8.0.4 the strategy continues to the next block as expected.
What sip version are you on?

Thanks.

[Kubig]

I have installed latest patch for SIP server - 8.1.001.17,but I tried a lot of version from 8.1.000.xx to current,and I have not noticed your behavior. Did you check the strategy,if the announcement block has correctly configured an error output? Could you check if SIP server generates an event EventTreatmentNotApplied and distributed it to the URS?
If I rename a file and that I call this prompt in strategy,SIP server jump on next block and generate EventTreatmentNotApplied

[René]

PFCCWA,

Could you post here snippet of URS log showing RequestApplyTreatment and following error response from SIP Server and reaction of URS?

R.

[PFCCWA]

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]

[Kubig]

You have to had configured wrongly something (strategy block,SIP/URS options,..). I have installed same versions and I did not see described behaviors. If an SIP receives an error "Fetch failed",generates EventNotTreatmentApplied and URS continue in strategy. Check your strategy design

[PFCCWA]

Doesnt explain why it works on sip 8.0.4 versions?..

I have checked the strategy within monitoring section and the call does not proceed out of the play announcement block, through red or green ports.
Will try latest SIP (..001.17)..

[Kubig]

It's true that I have never used SIP in version 8.0,only in 8.1 and as I wrote I did not register described behavior. Did you reload application template after upgrade (SIP 8.0 x 8.1)?What version of URS do you use?

[PFCCWA]

Tried URS 8.0 and 8.1.2, both had problem with SIP 8.1 but no issues with 8.0.4.
Am using sip 8.1 application template and configuration options, however did this with both versions (8.0 and 8.1) with only 8.0 working.

[Kubig]

If SIP server sent EventTreatmentNotApplied to the URS it looks like a bad behavior on URS side. URS have to continue to next block through red (error) output.

[René]

Based on provided log snippet, URS doesn't receive EventTreatmentNotApplied but EventRouteUsed. That means that SIP Server took over the interaction.

@PFCCWA - Would it be possible to post here snippet of SIP Server log showing request to play treatment, response from Media Server (error) and following operations.

R.

[PFCCWA]

Looking at the sip logs, tge EventTreatmentNotApplied is not sent to urs when using sip 8.1 - it is also applied to the ACD queue (attributrethisdn=111111 as per log) - and call fails.

If I use sip 8.0.4, the EventTreatmentNotApplied is applied to the route point (attributethisdn=RP1 as per log), and sent to urs - so call continues.

[Kubig]

Could you post SIP options export?

[PFCCWA]

sip config file below.
Tried latest sip release, same issue.
Tried default 8.1 template options, still occuring.

[TServer]
accept-dn-type = +extension +position +acdqueue +routedn +trunk +routequeue
acw-in-idle-force-ready = true
after-routing-timeout = 10
agent-emu-login-on-call = false
agent-group =
agent-logout-on-unreg = false
agent-logout-reassoc = false
agent-no-answer-action = none
agent-no-answer-overflow =
agent-no-answer-timeout = 15
agent-only-private-calls = false
agent-strict-id = false
am-detected = connect
ani-distribution = inbound-calls-only
audio-codecs = telephone-event,PCMU,PCMA,G723,G729,GSM
auto-logout-ready = true
auto-logout-timeout = 481
background-processing = false
background-timeout = 60 msec
backup-mode = none
backwds-compat-acw-behavior = false
busy-tone = music/busy_5sec
busy-tone-duration = 5
call-rq-gap = 0
cancel-monitor-on-disconnect = true
check-tenant-profile = false
clid-withheld-name = PRIVATE
collect-tone = music/collect
consult-user-data = separate
convert-otherdn = +agentid +reserveddn +fwd
correct-rqid = false
cos =
cpd-info-timeout = 30
customer-id = Resources
default-dn =
default-dn-type = none
default-monitor-mode = mute
default-monitor-scope = call
default-music = music/default.wav
default-video-file =
device-rq-gap = 0
dial-plan =
divert-on-ringing = false
dn-del-mode = never
dn-scope = undefined
dtmf-payload = 101
emergency-recording-cleanup-enabled = false
emergency-recording-filename =
emulate-login = on-RP
emulated-login-state = not-ready
enable-ims = false
enable-unknown-gateway = false
enforce-external-domains =
event-ringing-on-100trying = true
external-registrar =
extn-no-answer-overflow =
extn-no-answer-timeout = 15
fast-busy-tone = music/atb_5sec
fax-detected = drop
find-trunk-by-location = false
forced-notready = true
ims-default-icid-prefix =
ims-default-icid-suffix =
ims-default-orig-ioi =
ims-propagate-pcvector = false
ims-route =
inbound-bsns-calls = false
info-pass-through =
inherit-bsns-type = false
init-dnis-by-ruri = false
internal-bsns-calls = false
internal-registrar-domains =
internal-registrar-enabled = true
internal-registrar-persistent = true
intrusion-enabled = true
kpl-interval = 10
kpl-loss-rate = 10,100
kpl-tolerance = 3
legal-guard-time = 0
log-trace-flags = +iscc +cfg$dn -cfgserv +passwd +udata -devlink -sw -req -callops -conn -client
logout-on-disconnect = true
make-call-alert-info =
management-port = 3001
map-sip-errors = true
max-legs-per-sm = 0
max-pred-req-delay = 3
merged-user-data = main-only
monitor-internal-calls = true
msml-support = true
music-in-conference-file = music/conference.wav
music-in-queue-file = music/on_hold_pcma.wav
mwi-agent-enable = false
mwi-domain =
mwi-extension-enable = false
mwi-group-enable = false
mwi-host =
mwi-mode = SUBSCRIBE
mwi-port = 5061
nas-indication = none
nas-private = false
observing-routing-point =
outbound-bsns-calls = true
override-switch-acw = false
override-to-on-divert = false
p-asserted-identity =
parking-music = music/silence
partition-id = SipServerDefaultPartition
posn-no-answer-overflow =
posn-no-answer-timeout = 15
prd-dist-call-ans-time = 0
predictive-call-router-timeout = 0
preview-expired = 90
privacy =
propagated-call-type = false
recall-no-answer-timeout = 15
record-consult-calls = false
recording-filename =
refer-enabled = true
reg-interval = 60
registrar-default-timeout = 1800
releasing-party-report = false
restart-period = 20
ring-tone = music/RingTone.wav
ringing-on-route-point = true
route-failure-alarm-high-wm = 10
route-failure-alarm-low-wm = 1
route-failure-alarm-period = 0
router-timeout = 10
rq-conflict-check = true
server-role = 0
session-refresh-interval = 1800
set-notready-on-busy = false
silence-tone = music/silence
sip-address = 1**.**.***.**
sip-address-srv =
sip-call-retain-timeout = 1
sip-dtmf-send-rtp = true
sip-enable-100rel = true
sip-enable-call-info = false
sip-enable-moh = true
sip-enable-sdp-application-filter = false
sip-enable-sdp-codec-filter = false
sip-hold-rfc3264 = true
sip-invite-timeout = 0
sip-invite-treatment-timeout = 0
sip-ip-tos = 256
sip-port = 5060
sip-port-tls = 0
sip-preserve-contact = false
sip-proxy-headers-enabled = true
sip-replaces-mode = 0
sip-retry-timeout = 30
sip-ring-tone-mode = 0
sip-tls-cert =
sip-tls-cert-key =
sip-tls-trusted-ca =
sip-treatment-dtmf-interruptable = false
sip-treatments-continuous = false
stranded-call-redirection-limit = 4
stranded-calls-overflow =
stranded-on-arrival-calls-overflow =
subscription-timeout = 180
sync-emu-agent = false
timed-acw-in-idle = true
unknown-bsns-calls = false
unknown-xfer-merge-udata = false
untimed-wrap-up-value = 1000
use-display-name = false
user-data-limit = 16000
userdata-map-trans-prefix = X-Genesys
wrap-up-threshold = 0
wrap-up-time = 7
rq-expire-tmout = 0
rq-expire-tout = 0
enable-msml-media-services = true
enable-overload-control = false
http-port = 0
max-load-factor-percentage = 200
mime-body-type =
min-load-factor-percentage = 110
presence-enabled = false
sip-enable-sdp-encryption-removal = false
sip-force-rport = false
sip-interface =
sip-refer-to-sst-enabled = true
sync-emu-acw = false
xs-heartbeat-interval = 180

[log-filter]
default-filter-type = copy

[link-control]
ha-sync-dly-lnk-conn = false
link-alarm-high = 0
link-alarm-low = 0
quiet-cleanup = false
quiet-startup = false
reg-delay = 10
reg-silent = true
restart-cleanup-dly = 0
restart-cleanup-limit = 0
use-link-bandwidth = auto

[license]
license-file = 7260@[licensehost]
num-of-licenses = 100
num-sdn-licenses = 100

[extrouter]
cast-type = route direct-callid reroute direct-uui direct-ani direct-notoken dnis-pool direct-digits pullback route-uui direct-network-callid
cof-ci-defer-create = 0
cof-ci-defer-delete = 0
cof-ci-req-tout = 500 msec
cof-ci-wait-all = false
cof-feature = false
cof-rci-tout = 10 sec
compound-dn-representation = true
default-dn =
default-network-call-id-matching =
direct-digits-key = CDT_Track_Num
dn-for-unexpected-calls =
epp-tout = 0 sec
event-propagation = list
match-call-once = true
network-request-timeout = 20 sec
reconnect-tout = 5 sec
register-attempts = 5
register-tout = 2 sec
report-connid-changes = false
request-tout = 20 sec
resource-allocation-mode = circular
resource-load-maximum = 0
route-dn =
tcs-queue =
tcs-use = never
timeout = 60 sec
use-data-from = current
use-implicit-access-numbers = false
handle-vsp = all

[call-cleanup]
cleanup-idle-tout = 0
notify-idle-tout = 0
periodic-check-tout = 10 min

[backup-sync]
addp-remote-timeout = 60
addp-timeout = 30
addp-trace = on
protocol = default
sync-reconnect-tout = 20 sec

[agent-reservation]
collect-lower-priority-requests = true
reject-subsequent-request = true
request-collection-time = 100 msec
reservation-time = 10000 msec

[log-filter-data]
<kv-pair-key> = copy

[log]
all = D:\Program Files\GCTI\logs\sipserver\sipserver
expire = 3 day
keep-startup-file = true
message_format = short
segment = 10000
verbose = all
buffering = true
standard = network