Calls getting dropped

Discussion in 'General' started by hinzinho, Jan 13, 2010.

  1. hinzinho

    Joined:
    Sep 18, 2009
    Messages:
    461
    Likes Received:
    0
    I'm not sure what's going on, but we are getting dropped calls. There is no pattern to this and not all calls, just a few here and there. I have opened a ticket with our voice provider (PRI circuit) and they tested the circuit clean. I have looked at the Asterisk log files and didn't see. I have this system up for 2 months and then this problem started last Friday.

    What else can I try? Thank you!
     
  2. rafael

    Joined:
    May 14, 2007
    Messages:
    1,454
    Likes Received:
    1
    take a look to the logs, there may be information about the issue.
    Code:
    less /var/log/asterisk/full
    
    In real time you can try
    Code:
    tail -f /var/log/asterisk/full
    
    Best regards,

    Rafael
     
  3. hinzinho

    Joined:
    Sep 18, 2009
    Messages:
    461
    Likes Received:
    0
    I have checked the log numerous times but didn't see anything. Here's one example:

    Call was dropped at 2:50pm.
    Code:
    [Jan 12 14:49:52] VERBOSE[15417] logger.c:   == Manager 'admin' logged on from 127.0.0.1
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:   dialparties.agi: Caller ID name is '916373XXXX' number is '916373XXXX'
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:   dialparties.agi: Methodology of ring is  'ringall'
    .....
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:   dialparties.agi: ExtensionState: 4
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:   dialparties.agi: Extension 5995 has ExtensionState: 4
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     --  dialparties.agi: Checking CW and CFB status for extension 5995
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     --  dialparties.agi: dbset CALLTRACE/5995 to 916373XXXX
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     --  dialparties.agi: Filtered ARG3: 5000-5001-5002-5016-5995
    [Jan 12 14:49:52] VERBOSE[15417] logger.c:   == Manager 'admin' logged off from 127.0.0.1
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- AGI Script dialparties.agi completed, returning 0
    [Jan 12 14:49:52] DEBUG[15415] app_macro.c: Executed application: AGI
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Executing [s@macro-dial:7] Dial("DAHDI/1-1", "SIP/5000&SIP/5001&SIP/5002&SIP/5016&DAHDI/28|20|trM(auto-blkvm)") in new stack
    [Jan 12 14:49:52] NOTICE[15415] app_dial.c: Hey! chan DAHDI/1-1's context='macro-dial', and exten='s'
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Called 5000
    ....
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Called 5001
    ....
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Called 5002
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Called 5016
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- Called 28
    ....
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- DAHDI/28-1 is ringing
    ....
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- SIP/5001-1496ff20 is ringing
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- SIP/5002-149562b0 is ringing
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- SIP/5016-14e6d5e0 is ringing
    [Jan 12 14:49:52] VERBOSE[15415] logger.c:     -- SIP/5000-14b0e780 is ringing
    [Jan 12 14:49:54] VERBOSE[15415] logger.c:     -- DAHDI/28-1 is ringing
    [Jan 12 14:49:55] DEBUG[15415] chan_dahdi.c: Engaged echo training on channel 28
    [Jan 12 14:49:55] DEBUG[15415] chan_dahdi.c: channel 28 answered
    [Jan 12 14:49:55] VERBOSE[15415] logger.c:     -- DAHDI/28-1 answered DAHDI/1-1
    [Jan 12 14:49:55] VERBOSE[15415] logger.c:     -- Executing [s@macro-auto-blkvm:1] Set("DAHDI/28-1", "__MACRO_RESULT=") in new stack
    [Jan 12 14:49:55] DEBUG[15415] app_macro.c: Executed application: Set
    [Jan 12 14:49:55] VERBOSE[15415] logger.c:     -- Executing [s@macro-auto-blkvm:2] DBdel("DAHDI/28-1", "BLKVM/600/DAHDI/1-1") in new stack
    [Jan 12 14:49:55] VERBOSE[15415] logger.c:     -- DBdel: family=BLKVM, key=600/DAHDI/1-1
    ...
    [Jan 12 14:49:55] DEBUG[15415] app_macro.c: Executed application: dbDel
    [Jan 12 14:49:55] DEBUG[15415] app_dial.c: Macro exited with status 0
    ....
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Executing [h@macro-dial:1] Macro("SIP/5101-c05dfeb0", "hangupcall") in new stack
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/5101-c05dfeb0", "1?skiprg") in new stack
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Goto (macro-hangupcall,s,4)
    [Jan 12 14:49:57] DEBUG[15404] app_macro.c: Executed application: GotoIf
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/5101-c05dfeb0", "1?skipblkvm") in new stack
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Goto (macro-hangupcall,s,7)
    [Jan 12 14:49:57] DEBUG[15404] app_macro.c: Executed application: GotoIf
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/5101-c05dfeb0", "1?theend") in new stack
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Goto (macro-hangupcall,s,9)
    [Jan 12 14:49:57] DEBUG[15404] app_macro.c: Executed application: GotoIf
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("SIP/5101-c05dfeb0", "") in new stack
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/5101-c05dfeb0' in macro 'hangupcall'
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:   == Spawn h extension (macro-dial, h, 1) exited non-zero on 'SIP/5101-c05dfeb0'
    ...
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/5101-c05dfeb0' in macro 'dial'
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:   == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/5101-c05dfeb0' in macro 'exten-vm'
    [Jan 12 14:49:57] VERBOSE[15404] logger.c:   == Spawn extension (from-internal, 5034, 1) exited non-zero on 'SIP/5101-c05dfeb0'
    ...
    [Jan 12 14:50:01] VERBOSE[3235] logger.c:     -- Remote UNIX connection
    [Jan 12 14:50:02] VERBOSE[15421] logger.c:     -- Remote UNIX connection disconnected
    [Jan 12 14:50:14] VERBOSE[15415] logger.c:     -- Started music on hold, class 'default', on DAHDI/1-1
    [Jan 12 14:50:14] VERBOSE[15415] logger.c:     -- <DAHDI/28-1> Playing 'pbx-transfer' (language 'en')
    [Jan 12 14:50:18] VERBOSE[15415] logger.c:     -- Stopped music on hold on DAHDI/1-1
    [Jan 12 14:50:18] DEBUG[15415] chan_dahdi.c: New owner for channel 1 is DAHDI/1-1
    [Jan 12 14:50:18] VERBOSE[15415] logger.c:     -- Started music on hold, class 'default', on DAHDI/1-1
    [Jan 12 14:50:18] VERBOSE[15415] logger.c:   == Parked DAHDI/1-1 on 801@parkedcalls. Will timeout back to extension [macro-dial] s, 7 in 180 seconds
    [Jan 12 14:50:18] VERBOSE[15415] logger.c:     -- Added extension '801' priority 1 to parkedcalls
    [Jan 12 14:50:18] VERBOSE[15415] logger.c:     -- <DAHDI/28-1> Playing 'digits/8' (language 'en')
    [Jan 12 14:50:19] VERBOSE[15415] logger.c:     -- <DAHDI/28-1> Playing 'digits/0' (language 'en')
    [Jan 12 14:50:19] VERBOSE[15415] logger.c:     -- <DAHDI/28-1> Playing 'digits/1' (language 'en')
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Executing [h@macro-dial:1] Macro("Parked/DAHDI/1-1<ZOMBIE>", "hangupcall") in new stack
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("Parked/DAHDI/1-1<ZOMBIE>", "1?skiprg") in new stack
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Goto (macro-hangupcall,s,4)
    [Jan 12 14:50:20] DEBUG[15415] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("Parked/DAHDI/1-1<ZOMBIE>", "1?skipblkvm") in new stack
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Goto (macro-hangupcall,s,7)
    [Jan 12 14:50:20] DEBUG[15415] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("Parked/DAHDI/1-1<ZOMBIE>", "1?theend") in new stack
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Goto (macro-hangupcall,s,9)
    [Jan 12 14:50:20] DEBUG[15415] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("Parked/DAHDI/1-1<ZOMBIE>", "") in new stack
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'Parked/DAHDI/1-1<ZOMBIE>' in macro 'hangupcall'
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:   == Spawn h extension (macro-dial, h, 1) exited non-zero on 'Parked/DAHDI/1-1<ZOMBIE>'
    [Jan 12 14:50:20] VERBOSE[15415] logger.c:     -- Hungup 'DAHDI/28-1'
    [Jan 12 14:50:20] DEBUG[15415] app_macro.c: Executed application: Dial
    [Jan 12 14:50:20] DEBUG[15415] app_macro.c: Extension s, macroexten 600, priority 7 returned normally even though call was hung up
    [Jan 12 14:50:22] VERBOSE[15424] logger.c:     -- Starting simple switch on 'DAHDI/28-1'
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [5020@from-internal:1] Macro("DAHDI/28-1", "exten-vm|5020|5020") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:1] Macro("DAHDI/28-1", "user-callerid") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:1] Set("DAHDI/28-1", "AMPUSER=5995") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:2] GotoIf("DAHDI/28-1", "0?report") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:3] ExecIf("DAHDI/28-1", "1|Set|REALCALLERIDNUM=5995") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: ExecIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:4] Set("DAHDI/28-1", "AMPUSER=5995") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:5] Set("DAHDI/28-1", "AMPUSERCIDNAME=Coreless") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/28-1", "0?report") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:7] Set("DAHDI/28-1", "AMPUSERCID=5995") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:8] Set("DAHDI/28-1", "CALLERID(all)="Coreless" <5995>") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:9] ExecIf("DAHDI/28-1", "0|Set|CHANNEL(language)=") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: ExecIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:10] GotoIf("DAHDI/28-1", "0?continue") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:11] Set("DAHDI/28-1", "__TTL=64") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:12] GotoIf("DAHDI/28-1", "1?continue") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Goto (macro-user-callerid,s,19)
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-user-callerid:19] NoOp("DAHDI/28-1", "Using CallerID "Coreless" <5995>") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Noop
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Macro
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:2] Set("DAHDI/28-1", "RingGroupMethod=none") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:3] Set("DAHDI/28-1", "VMBOX=5020") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:4] Set("DAHDI/28-1", "EXTTOCALL=5020") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] DEBUG[15424] func_db.c: DB: CFU/5020 not found in database.
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:5] Set("DAHDI/28-1", "CFUEXT=") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] DEBUG[15424] func_db.c: DB: CFB/5020 not found in database.
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:6] Set("DAHDI/28-1", "CFBEXT=") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:7] Set("DAHDI/28-1", "RT=15") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Set
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:8] Macro("DAHDI/28-1", "record-enable|5020|IN") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-record-enable:1] MacroExit("DAHDI/28-1", "") in new stack
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: Macro
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-exten-vm:9] Macro("DAHDI/28-1", "dial|15|tr|5020") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-dial:1] GotoIf("DAHDI/28-1", "1?dial") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Goto (macro-dial,s,3)
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-dial:3] AGI("DAHDI/28-1", "dialparties.agi") in new stack
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:   dialparties.agi: Starting New Dialparties.agi
    [Jan 12 14:50:31] VERBOSE[15426] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Jan 12 14:50:31] VERBOSE[15426] logger.c: Found
    [Jan 12 14:50:31] VERBOSE[15426] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Jan 12 14:50:31] VERBOSE[15426] logger.c: Found
    [Jan 12 14:50:31] VERBOSE[15426] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Jan 12 14:50:31] VERBOSE[15426] logger.c: Found
    [Jan 12 14:50:31] WARNING[15426] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
    [Jan 12 14:50:31] VERBOSE[15426] logger.c:   == Manager 'admin' logged on from 127.0.0.1
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:   dialparties.agi: Caller ID name is 'Coreless' number is '5995'
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:   dialparties.agi: Methodology of ring is  'none'
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     --  dialparties.agi: Added extension 5020 to extension map
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     --  dialparties.agi: Extension 5020 cf is disabled
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     --  dialparties.agi: Extension 5020 do not disturb is disabled
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:   dialparties.agi: ExtensionState: 0
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     --  dialparties.agi: dbset CALLTRACE/5020 to 5995
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     --  dialparties.agi: Filtered ARG3: 5020
    [Jan 12 14:50:31] VERBOSE[15426] logger.c:   == Manager 'admin' logged off from 127.0.0.1
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- AGI Script dialparties.agi completed, returning 0
    [Jan 12 14:50:31] DEBUG[15424] app_macro.c: Executed application: AGI
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Executing [s@macro-dial:7] Dial("DAHDI/28-1", "SIP/5020|15|tr") in new stack
    [Jan 12 14:50:31] NOTICE[15424] app_dial.c: Hey! chan DAHDI/28-1's context='macro-dial', and exten='s'
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- Called 5020
    .....
    [Jan 12 14:50:31] VERBOSE[15424] logger.c:     -- SIP/5020-149c1130 is ringing
    [Jan 12 14:50:36] VERBOSE[15424] logger.c:     -- SIP/5020-149c1130 answered DAHDI/28-1
    [Jan 12 14:50:36] DEBUG[15424] chan_dahdi.c: Took DAHDI/28-1 off hook
    .....
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Executing [h@macro-dial:1] Macro("DAHDI/28-1", "hangupcall") in new stack
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("DAHDI/28-1", "1?skiprg") in new stack
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Goto (macro-hangupcall,s,4)
    [Jan 12 14:50:45] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("DAHDI/28-1", "1?skipblkvm") in new stack
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Goto (macro-hangupcall,s,7)
    [Jan 12 14:50:45] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("DAHDI/28-1", "1?theend") in new stack
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Goto (macro-hangupcall,s,9)
    [Jan 12 14:50:45] DEBUG[15424] app_macro.c: Executed application: GotoIf
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("DAHDI/28-1", "") in new stack
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'DAHDI/28-1' in macro 'hangupcall'
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:   == Spawn h extension (macro-dial, h, 1) exited non-zero on 'DAHDI/28-1'
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'DAHDI/28-1' in macro 'dial'
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:   == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'DAHDI/28-1' in macro 'exten-vm'
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:   == Spawn extension (from-internal, 5020, 1) exited non-zero on 'DAHDI/28-1'
    [Jan 12 14:50:45] VERBOSE[15424] logger.c:     -- Hungup 'DAHDI/28-1'
    .... 
    [Jan 12 14:50:48] VERBOSE[15427] logger.c:     -- Executing [801@from-internal:1] ParkedCall("SIP/5020-c05dfeb0", "801") in new stack
    ....
    [Jan 12 14:50:48] VERBOSE[15427] logger.c:     -- Stopped music on hold on DAHDI/1-1
    [Jan 12 14:50:48] VERBOSE[15427] logger.c:     -- Channel SIP/5020-c05dfeb0 connected to parked call 801
    ....
    [Jan 12 14:50:49] DEBUG[3357] chan_dahdi.c: Message status for 5995@device changed from -1 to 0 on 28
    [Jan 12 14:50:49] VERBOSE[15428] logger.c:     -- Starting simple switch on 'DAHDI/28-1'
    [Jan 12 14:50:52] DEBUG[15428] chan_dahdi.c: waitfordigit returned < 0...
    [Jan 12 14:50:52] VERBOSE[15428] logger.c:     -- Hungup 'DAHDI/28-1'
    [Jan 12 14:50:56] DEBUG[3357] chan_dahdi.c: Message status for 5995@device changed from -1 to 0 on 28
    [Jan 12 14:53:42] VERBOSE[3235] logger.c:     -- Remote UNIX connection
    [Jan 12 14:53:43] VERBOSE[15430] logger.c:     -- Remote UNIX connection disconnected
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [h@from-internal:1] Macro("SIP/5020-c05dfeb0", "hangupcall") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/5020-c05dfeb0", "1?skiprg") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,4)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/5020-c05dfeb0", "1?skipblkvm") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,7)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/5020-c05dfeb0", "1?theend") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,9)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("SIP/5020-c05dfeb0", "") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/5020-c05dfeb0' in macro 'hangupcall'
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:   == Spawn h extension (from-internal, h, 1) exited non-zero on 'SIP/5020-c05dfeb0'
    [Jan 12 14:54:41] DEBUG[15427] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1
    [Jan 12 14:54:41] DEBUG[15427] chan_dahdi.c: Not yet hungup...  Calling hangup once with icause, and clearing call
    [Jan 12 14:54:41] DEBUG[15427] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Hungup 'DAHDI/1-1'
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:   == Spawn extension (from-internal, 801, 1) exited non-zero on 'SIP/5020-c05dfeb0'
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [h@from-internal:1] Macro("SIP/5020-c05dfeb0", "hangupcall") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/5020-c05dfeb0", "1?skiprg") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,4)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/5020-c05dfeb0", "1?skipblkvm") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,7)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/5020-c05dfeb0", "1?theend") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Goto (macro-hangupcall,s,9)
    [Jan 12 14:54:41] DEBUG[15427] app_macro.c: Executed application: GotoIf
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:     -- Executing [s@macro-hangupcall:9] Hangup("SIP/5020-c05dfeb0", "") in new stack
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/5020-c05dfeb0' in macro 'hangupcall'
    [Jan 12 14:54:41] VERBOSE[15427] logger.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/5020-c05dfeb0'
    
     
  4. hinzinho

    Joined:
    Sep 18, 2009
    Messages:
    461
    Likes Received:
    0
    I think I found my issue. After searching the web, I came across articles referring to IRQ issue. I then checked /var/log/messages and saw this on my log:
    Code:
    Jan 12 09:02:13 elastix kernel: wcte12xp0: Missed interrupt. Increasing latency to 4 ms in order to compensate.
    
    The time frame of this "missed interrupt" matched exactly to one of my dropped call. Strange is that I didn't see a message for each of the other dropped calls.

    I then found this article which was very helpfully:
    http://www.elastixconnection.com/index. ... &Itemid=77

    When I ran the /proc/interrupts command on my system, I get the result below. I know I need to move my Digium cards to another slot, but that is difficult to do since I only have 1 more available slots.

    Is it normal to see such high numbers for wcte12xp0 and wctdm24xxp0? If I do a "watch -n 1 cat /proc/interrupts", I see the numbers increment at 1000 per second.


    Code:
    [root@elastix ~]# cat /proc/interrupts
               CPU0       CPU1
      0:  189572436          0    IO-APIC-edge  timer
      1:          5          0    IO-APIC-edge  i8042
      8:          0          0    IO-APIC-edge  rtc
      9:          0          0   IO-APIC-level  acpi
     50:     626403          0         PCI-MSI  ahci
     58:   13584238          0         PCI-MSI  eth0
    169:  189495149          0   IO-APIC-level  uhci_hcd:usb1, wcte12xp0
    177:  191492652          0   IO-APIC-level  ata_piix, wctdm24xxp0
    185:          0          0   IO-APIC-level  uhci_hcd:usb2
    NMI:      14496       2934
    LOC:  190834354  190831842
    ERR:          0
    MIS:          0
    
     

Share This Page