Dropping calls - all lights in buddy list flash at the same time
I am working with a client on a new asterisk trixbox (2.6.1) installation. At random times throughout the day, all of the buddy lights on their phone will momentarily blink. If they are on the phone when this happens, the call drops. Also, if other people are on the line at this time their call drops as well. Here is a snippet from the log file during a period of time that this happened (They said at 4:43 pm). Let me know if you see anything that could be causing this.
Thanks
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [6167742031@from-internal:1] Macro("SIP/213-xxxxxxxx", "user-callerid|SKIPTTL|") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/213-xxxxxxxx", "user-callerid: device 213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/213-xxxxxxxx", "AMPUSER=213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/213-xxxxxxxx", "0?report") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/213-xxxxxxxx", "1|Set|REALCALLERIDNUM=213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: ExecIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:5] NoOp("SIP/213-xxxxxxxx", "REALCALLERIDNUM is 213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:6] Set("SIP/213-xxxxxxxx", "AMPUSER=213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/213-xxxxxxxx", "AMPUSERCIDNAME=John Doe") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/213-xxxxxxxx", "0?report") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/213-xxxxxxxx", "AMPUSERCID=213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/213-xxxxxxxx", "CALLERID(all)="John Doe" <213>") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/213-xxxxxxxx", "REALCALLERIDNUM=213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:12] ExecIf("SIP/213-xxxxxxxx", "0|Set|CHANNEL(language)=") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: ExecIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/213-xxxxxxxx", "TTL: ARG1: SKIPTTL") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/213-xxxxxxxx", "1?continue") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-user-callerid,s,23)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/213-xxxxxxxx", "Using CallerID "John Doe" <213>") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [6167742031@from-internal:2] Set("SIP/213-xxxxxxxx", "_NODEST=") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [6167742031@from-internal:3] Macro("SIP/213-xxxxxxxx", "record-enable|213|OUT|") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/213-xxxxxxxx", "0?2:4") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-record-enable,s,4)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/213-xxxxxxxx", "recordingcheck|20090701-164113|1246480873.0") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Jul 1 16:41:13] VERBOSE[23865] logger.c: recordingcheck|20090701-164113|1246480873.0: Outbound recording not enabled
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- AGI Script recordingcheck completed, returning 0
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: AGI
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-record-enable:5] NoOp("SIP/213-xxxxxxxx", "No recording needed") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [6167742031@from-internal:4] Macro("SIP/213-xxxxxxxx", "dialout-trunk|1|6167742031||") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/213-xxxxxxxx", "DIAL_TRUNK=1") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] DEBUG[23865] func_db.c: DB: AMPUSER/213/pinless not found in database.
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/213-xxxxxxxx", "0|Authenticate|") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: ExecIf
[Jul 1 16:41:13] DEBUG[23865] func_db.c: DB: AMPUSER/213/pinless not found in database.
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/213-xxxxxxxx", "0?disabletrunk|1") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/213-xxxxxxxx", "DIAL_NUMBER=6167742031") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/213-xxxxxxxx", "DIAL_TRUNK_OPTIONS=tr") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/213-xxxxxxxx", "GROUP()=OUT_1") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/213-xxxxxxxx", "1?nomax") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-dialout-trunk,s,9)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/213-xxxxxxxx", "0?skipoutcid") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/213-xxxxxxxx", "DIAL_TRUNK_OPTIONS=") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/213-xxxxxxxx", "outbound-callerid|1") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/213-xxxxxxxx", "0|SetCallerPres|") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: ExecIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:2] GotoIf("SIP/213-xxxxxxxx", "1?start") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-outbound-callerid,s,4)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:4] NoOp("SIP/213-xxxxxxxx", "REALCALLERIDNUM is 213") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("SIP/213-xxxxxxxx", "1?normcid") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-outbound-callerid,s,10)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/213-xxxxxxxx", "USEROUTCID=") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] DEBUG[23865] func_db.c: DB: DEVICE/213/emergency_cid not found in database.
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/213-xxxxxxxx", "EMERGENCYCID=") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:12] Set("SIP/213-xxxxxxxx", "TRUNKOUTCID=") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/213-xxxxxxxx", "1?trunkcid") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-outbound-callerid,s,17)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:17] GotoIf("SIP/213-xxxxxxxx", "1?usercid") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-outbound-callerid,s,19)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:19] GotoIf("SIP/213-xxxxxxxx", "1?report") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Goto (macro-outbound-callerid,s,23)
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-outbound-callerid:23] NoOp("SIP/213-xxxxxxxx", "CallerID set to "John Doe" <213>") in new stack
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Noop
[Jul 1 16:41:13] DEBUG[23865] app_macro.c: Executed application: Macro
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:12] AGI("SIP/213-xxxxxxxx", "fixlocalprefix") in new stack
[Jul 1 16:41:13] VERBOSE[23865] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Jul 1 16:41:14] VERBOSE[23865] logger.c: == fixlocalprefix: Dialpattern xxxxxxxxxx matched. 6167742031 -> 6167742031
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: AGI
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/213-xxxxxxxx", "OUTNUM=6167742031") in new stack
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/213-xxxxxxxx", "custom=ZAP/g0") in new stack
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: Set
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/213-xxxxxxxx", "1?gocall") in new stack
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/213-xxxxxxxx", "dialout-trunk-predial-hook|") in new stack
[Jul 1 16:41:14] WARNING[23865] app_macro.c: Context 'macro-dialout-trunk-predial-hook' for macro 'dialout-trunk-predial-hook' lacks 's' extension, priority 1
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: Macro
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/213-xxxxxxxx", "0?bypass|1") in new stack
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:19] GotoIf("SIP/213-xxxxxxxx", "0?customtrunk") in new stack
[Jul 1 16:41:14] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Executing [s@macro-dialout-trunk:20] Dial("SIP/213-xxxxxxxx", "ZAP/g0/6167742031|300|") in new stack
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Called g0/6167742031
[Jul 1 16:41:14] DEBUG[23844] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Jul 1 16:41:14] VERBOSE[23865] logger.c: -- Zap/1-1 is proceeding passing it to SIP/213-xxxxxxxx
[Jul 1 16:41:16] VERBOSE[23865] logger.c: -- Zap/1-1 is ringing
[Jul 1 16:41:22] DEBUG[23844] chan_zap.c: Echo cancellation already on
[Jul 1 16:41:22] VERBOSE[23865] logger.c: -- Zap/1-1 answered SIP/213-xxxxxxxx
[Jul 1 16:47:54] DEBUG[23865] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Jul 1 16:47:54] DEBUG[23865] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Jul 1 16:47:54] DEBUG[23865] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Hungup 'Zap/1-1'
[Jul 1 16:47:54] VERBOSE[23865] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/213-xxxxxxxx' in macro 'dialout-trunk'
[Jul 1 16:47:54] VERBOSE[23865] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on 'SIP/213-xxxxxxxx'
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/213-xxxxxxxx", "hangupcall|") in new stack
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/213-xxxxxxxx", "w") in new stack
[Jul 1 16:47:54] DEBUG[23865] app_macro.c: Executed application: ResetCDR
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/213-xxxxxxxx", "") in new stack
[Jul 1 16:47:54] DEBUG[23865] app_macro.c: Executed application: NoCDR
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/213-xxxxxxxx", "1?skiprg") in new stack
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Goto (macro-hangupcall,s,6)
[Jul 1 16:47:54] DEBUG[23865] app_macro.c: Executed application: GotoIf
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/213-xxxxxxxx", "1?skipblkvm") in new stack
[Jul 1 16:47:54] VERBOSE[23865] logger.c: -- Goto (macro-hangupcall,s,9)
[Jul 1 16:47:54] DEBUG[23865] app_macro.c: Executed application: GotoIf

Member Since:
2009-07-01