Receptionist hears IVR when answering call

Discussion in 'General' started by westcomlimited, Dec 6, 2010.

  1. westcomlimited

    Joined:
    Dec 3, 2010
    Messages:
    14
    Likes Received:
    0
    I have just migrated our system from Trixbox to Elastix.
    So far so good apart from one of the issues we had on Trixbox has followed over.

    When our Receptionist answers an incoming call from outside she sometimes hears the main IVR that the outside call might hear. This does not happen every time, and I think when it does the person on the outside call can hear the Receptionist but the Receptionist cannot hear them, only brief hold music then the IVR.

    I think I have a similar problem as described here

    However unlike that thread I AM using linksys SPA devices connecting to analog phone lines. We will be scrapping this in the next 2 months, but until then I need to try fix this issue as it seems to have gotten slightly worse on the Elastix install.

    I am currently waiting for the problem to occur again so I can paste the log here. As it happens at random it is hard to track down in the logs.
     
  2. westcomlimited

    Joined:
    Dec 3, 2010
    Messages:
    14
    Likes Received:
    0
    Here is a log file I found from some time ago when the problem happened on the old trixbox system.


    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:2] Answer("SIP/phl-s-104-b7603ef8", "") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:3] Set("SIP/phl-s-104-b7603ef8", "__BLKVM_OVERRIDE=BLKVM/100/SIP/phl-s-104-b7603ef8") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:4] Set("SIP/phl-s-104-b7603ef8", "__BLKVM_BASE=100") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:5] Set("SIP/phl-s-104-b7603ef8", "DB(BLKVM/100/SIP/phl-s-104-b7603ef8)=TRUE") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:6] ExecIf("SIP/phl-s-104-b7603ef8", "1|Set|_DIAL_OPTIONS=trM(auto-blkvm)") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:7] Set("SIP/phl-s-104-b7603ef8", "__NODEST=100") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:8] Set("SIP/phl-s-104-b7603ef8", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q100-20100326-091259-1269547969.664") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:9] Set("SIP/phl-s-104-b7603ef8", "__MOHCLASS=default") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Executing [100@ext-queues:10] Queue("SIP/phl-s-104-b7603ef8", "100|t|||60") in new stack
    [Mar 26 09:12:59] VERBOSE[29686] logger.c: -- Started music on hold, class 'default', on SIP/phl-s-104-b7603ef8
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [101@from-internal:1] Macro("Local/101@from-internal-901f,2", "exten-vm|101|101") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:1] Macro("Local/101@from-internal-901f,2", "user-callerid|") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:1] Set("Local/101@from-internal-901f,2", "AMPUSER=9603096") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("Local/101@from-internal-901f,2", "1?report") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Goto (macro-user-callerid,s,10)
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("Local/101@from-internal-901f,2", "0?continue") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:11] Set("Local/101@from-internal-901f,2", "__TTL=63") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:12] GotoIf("Local/101@from-internal-901f,2", "1?continue") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Goto (macro-user-callerid,s,19)
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("Local/101@from-internal-901f,2", "Using CallerID "9603096" <9603096>") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Noop
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Macro
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:2] Set("Local/101@from-internal-901f,2", "RingGroupMethod=none") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:3] Set("Local/101@from-internal-901f,2", "VMBOX=101") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:4] Set("Local/101@from-internal-901f,2", "EXTTOCALL=101") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] DEBUG[29693] func_db.c: DB: CFU/101 not found in database.
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:5] Set("Local/101@from-internal-901f,2", "CFUEXT=") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] DEBUG[29693] func_db.c: DB: CFB/101 not found in database.
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:6] Set("Local/101@from-internal-901f,2", "CFBEXT=") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:7] Set("Local/101@from-internal-901f,2", "RT=24") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Set
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:8] Macro("Local/101@from-internal-901f,2", "record-enable|101|IN") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("Local/101@from-internal-901f,2", "1?check") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Goto (macro-record-enable,s,4)
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-record-enable:4] ExecIf("Local/101@from-internal-901f,2", "0|MacroExit|") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: ExecIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-record-enable:5] GotoIf("Local/101@from-internal-901f,2", "0?Group:OUT") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Goto (macro-record-enable,s,15)
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-record-enable:15] GotoIf("Local/101@from-internal-901f,2", "1?IN") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Goto (macro-record-enable,s,20)
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-record-enable:20] ExecIf("Local/101@from-internal-901f,2", "1|MacroExit|") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: Macro
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-exten-vm:9] Macro("Local/101@from-internal-901f,2", "dial|24|trM(auto-blkvm)|101") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-dial:1] GotoIf("Local/101@from-internal-901f,2", "0?dial") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: GotoIf
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-dial:2] SetMusicOnHold("Local/101@from-internal-901f,2", "default") in new stack
    [Mar 26 09:12:59] DEBUG[29693] app_macro.c: Executed application: SetMusicOnHold
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Executing [s@macro-dial:3] AGI("Local/101@from-internal-901f,2", "dialparties.agi") in new stack
    [Mar 26 09:12:59] VERBOSE[29693] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi

    [Mar 26 09:09:02] WARNING[29533] app_queue.c: The device state of this queue member, Local/101@from-internal/n, is still 'Not in Use' when it probably should not be! Please check UPGRADE.txt for correct configuration settings.

    [Mar 26 09:10:04] WARNING[29599] app.c: No audio available on SIP/phl-s-102-b7629d00??

    [Mar 26 09:13:12] WARNING[13364] chan_sip.c: SUBSCRIBE failure: unrecognized format: 'multipart/related' pvt: subscribed: 0, stateid: -1, laststate: 0, dialogver: 0, subscribecont: '', subscribeuri: ''
     

Share This Page