NO JOY CID 20 second delay after answering

[wlraider70]

When I call in on the GV line it answers immediately. However, there is a 20 second delay before the greeting plays. This happens on both GV trunks, but not on others. The delay is surrounding the CID superfecta (is that a real word) so i tried to disable it, bit no dice. This error started after a power outage.

pbx*CLI> 
    -- Executing [s@im-w1iv01c32gmailcom:1] NoOp("Motif/+1714851xxxx-1032", "Receiving GoogleVoice on DID: 1860415xxxx") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:2] NoOp("Motif/+1714851xxxx-1032", "s") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:3] Set("Motif/+1714851xxxx-1032", "CALLERID(name)=+1714851xxxx") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:4] GotoIf("Motif/+1714851xxxx-1032", "0?nextstop") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:5] Set("Motif/+1714851xxxx-1032", "CALLERID(name)=714851xxxx") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:6] GotoIf("Motif/+1714851xxxx-1032", "1?notrim") in new stack
    -- Goto (im-w1iv01c32gmailcom,s,8)
    -- Executing [s@im-w1iv01c32gmailcom:8] Set("Motif/+1714851xxxx-1032", "CALLERID(number)=714851xxxx") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:9] Wait("Motif/+1714851xxxx-1032", "1") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:10] Answer("Motif/+1714851xxxx-1032", "") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:11] SendDTMF("Motif/+1714851xxxx-1032", "1") in new stack
    -- Executing [s@im-w1iv01c32gmailcom:12] Goto("Motif/+1714851xxxx-1032", "from-trunk,1860415xxxx,1") in new stack
    -- Goto (from-trunk,1860415xxxx,1)
    -- Executing [1860415xxxx@from-trunk:1] Set("Motif/+1714851xxxx-1032", "__FROM_DID=1860415xxxx") in new stack
    -- Executing [1860415xxxx@from-trunk:2] Gosub("Motif/+1714851xxxx-1032", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("Motif/+1714851xxxx-1032", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("Motif/+1714851xxxx-1032", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("Motif/+1714851xxxx-1032", "") in new stack
    -- Executing [1860415xxxx@from-trunk:3] Set("Motif/+1714851xxxx-1032", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
    -- Executing [1860415xxxx@from-trunk:4] AGI("Motif/+1714851xxxx-1032", "/var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
    -- Launched AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi

****~10 seconds*****

 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta is Answering the Channel
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Scheme is ALL
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The DID passed from Asterisk is: 1860415xxxx
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The number passed from Asterisk is: 714851xxxx
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: The CID name passed from Asterisk is: 714851xxxx
 /var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Superfecta: Executing Scheme..
   
****~10 seconds*****

 -- <Motif/+1714851xxxx-1032>AGI Script /var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
    -- Executing [1860415xxxx@from-trunk:5] Set("Motif/+1714851xxxx-1032", "CALLERID(name)=") in new stack
    -- Executing [1860415xxxx@from-trunk:6] Set("Motif/+1714851xxxx-1032", "CDR(did)=1860415xxxx") in new stack
    -- Executing [1860415xxxx@from-trunk:7] ExecIf("Motif/+1714851xxxx-1032", "1 ?Set(CALLERID(name)=714851xxxx)") in new stack
    -- Executing [1860415xxxx@from-trunk:8] Set("Motif/+1714851xxxx-1032", "CHANNEL(musicclass)=default") in new stack
    -- Executing [1860415xxxx@from-trunk:9] Set("Motif/+1714851xxxx-1032", "__MOHCLASS=default") in new stack
    -- Executing [1860415xxxx@from-trunk:10] Set("Motif/+1714851xxxx-1032", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [1860415xxxx@from-trunk:11] Set("Motif/+1714851xxxx-1032", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [1860415xxxx@from-trunk:12] Goto("Motif/+1714851xxxx-1032", "app-daynight,0,1") in new stack
    -- Goto (app-daynight,0,1)
    -- Executing [0@app-daynight:1] GotoIf("Motif/+1714851xxxx-1032", "0?app-announcement-1,s,1:ivr-3,s,1") in new stack
    -- Goto (ivr-3,s,1)
    -- Executing [s@ivr-3:1] Set("Motif/+1714851xxxx-1032", "TIMEOUT_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-3:2] Set("Motif/+1714851xxxx-1032", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-3:3] Set("Motif/+1714851xxxx-1032", "_IVR_CONTEXT_ivr-3=") in new stack
    -- Executing [s@ivr-3:4] Set("Motif/+1714851xxxx-1032", "_IVR_CONTEXT=ivr-3") in new stack
    -- Executing [s@ivr-3:5] Set("Motif/+1714851xxxx-1032", "__IVR_RETVM=RETURN") in new stack
    -- Executing [s@ivr-3:6] GotoIf("Motif/+1714851xxxx-1032", "1?skip") in new stack
    -- Goto (ivr-3,s,9)
    -- Executing [s@ivr-3:9] Set("Motif/+1714851xxxx-1032", "IVR_MSG=custom/JC-main3") in new stack
    -- Executing [s@ivr-3:10] Set("Motif/+1714851xxxx-1032", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-3:11] ExecIf("Motif/+1714851xxxx-1032", "1?Background(custom/JC-main3)") in new stack
    -- <Motif/+1714851xxxx-1032> Playing 'custom/JC-main3.slin' (language 'en')
  == Spawn extension (ivr-3, s, 11) exited non-zero on 'Motif/+1714851xxxx-1032'
    -- Executing [h@ivr-3:1] Hangup("Motif/+1714851xxxx-1032", "") in new stack
  == Spawn extension (ivr-3, h, 1) exited non-zero on 'Motif/+1714851xxxx-1032'

 

[atsak]

Maybe hard drive got a bit of a read error on it? Unusual but would explain the delay. Run fsck?