[2007-11-09 14:34:05] VERBOSE[7683] logger.c: [2007-11-09 14:34:05]     -- Accepting AUTHENTICATED call from 10.1.1.249:
       > requested format = ulaw,
       > requested prefs = (ulaw),
       > actual format = ulaw,
       > host prefs = (ulaw),
       > priority = mine
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:1] NoOp("IAX2/rpc-pbx-peo-02-58", "Finding channel for local exten 7608") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:2] Set("IAX2/rpc-pbx-peo-02-58", "channel=9") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:3] NoOp("IAX2/rpc-pbx-peo-02-58", "Pre-AGI CallerID(num): 7099") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:4] NoOp("IAX2/rpc-pbx-peo-02-58", "Pre-AGI CallerID(name): Porter, Bryce") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:5] AGI("IAX2/rpc-pbx-peo-02-58", "callerid-test.agi") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Launched AGI Script /var/lib/asterisk/agi-bin/callerid-test.agi
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- AGI Script callerid-test.agi completed, returning 0
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:6] NoOp("IAX2/rpc-pbx-peo-02-58", "Post-AGI CallerID(num): 7099") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:7] NoOp("IAX2/rpc-pbx-peo-02-58", "Post-AGI CallerID(name): Porter, Bryce") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:8] NoOp("IAX2/rpc-pbx-peo-02-58", "Calling 7608 on channel Zap/9") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [7608@from-iax:9] Macro("IAX2/rpc-pbx-peo-02-58", "stdexten|7608|Zap/9") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [s@macro-stdexten:1] Dial("IAX2/rpc-pbx-peo-02-58", "Zap/9|20|tT") in new stack
[2007-11-09 14:34:05] WARNING[6550] app_dial.c: Unable to create channel of type 'Zap' (cause 17 - User busy)
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]   == Everyone is busy/congested at this time (1:1/0/0)
[2007-11-09 14:34:05] DEBUG[6550] app_macro.c: Executed application: Dial
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [s@macro-stdexten:2] Goto("IAX2/rpc-pbx-peo-02-58", "s-BUSY|1") in new stack
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Goto (macro-stdexten,s-BUSY,1)
[2007-11-09 14:34:05] DEBUG[6550] app_macro.c: Executed application: Goto
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- Executing [s-BUSY@macro-stdexten:1] VoiceMail("IAX2/rpc-pbx-peo-02-58", "b7696") in new stack
[2007-11-09 14:34:05] DEBUG[6550] res_config_mysql.c: MySQL RealTime: Everything is fine.
[2007-11-09 14:34:05] DEBUG[6550] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM voicemail WHERE mailbox = '7696' AND context = 'default'
[2007-11-09 14:34:05] VERBOSE[6550] logger.c: [2007-11-09 14:34:05]     -- <IAX2/rpc-pbx-peo-02-58> Playing '/var/spool/asterisk/voicemail/default/7696/greet' (language 'en')
[2007-11-09 14:34:05] DEBUG[7677] chan_iax2.c: Ooh, voice format changed to 4

[2007-11-09 14:34:06] DEBUG[7679] chan_iax2.c: Immediately destroying 58, having received hangup
[2007-11-09 14:34:06] VERBOSE[6550] logger.c: [2007-11-09 14:34:06]   == Spawn extension (macro-stdexten, s-BUSY, 1) exited non-zero on 'IAX2/rpc-pbx-peo-02-58' in macro 'stdexten'
[2007-11-09 14:34:06] VERBOSE[6550] logger.c: [2007-11-09 14:34:06]   == Spawn extension (macro-stdexten, s-BUSY, 1) exited non-zero on 'IAX2/rpc-pbx-peo-02-58'
[2007-11-09 14:34:06] DEBUG[6550] chan_iax2.c: We're hanging up IAX2/rpc-pbx-peo-02-58 now...
[2007-11-09 14:34:06] DEBUG[6550] chan_iax2.c: Really destroying IAX2/rpc-pbx-peo-02-58 now...
[2007-11-09 14:34:06] VERBOSE[6550] logger.c: [2007-11-09 14:34:06]     -- Hungup 'IAX2/rpc-pbx-peo-02-58'