# Call 1

May 24 11:01:17 VERBOSE[24223] logger.c:     -- Starting simple switch on 'Zap/4-1'
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Executing Set("Zap/4-1", "TIMEOUT(digit)=5") in new stack
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Digit timeout set to 5
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Executing Set("Zap/4-1", "TIMEOUT(response)=15") in new stack
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Response timeout set to 15
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Executing Authenticate("Zap/4-1", "321") in new stack
May 24 11:01:20 VERBOSE[24223] logger.c:     -- Playing 'agent-pass' (language 'en')
May 24 11:01:23 VERBOSE[24223] logger.c:     -- Playing 'auth-thankyou' (language 'en')
May 24 11:01:24 VERBOSE[24223] logger.c:     -- Executing NoOp("Zap/4-1", "CALLERIDNUM=4989666555444 CALLERIDNAME=") in new stack
May 24 11:01:24 VERBOSE[24223] logger.c:     -- Executing DeadAGI("Zap/4-1", "callback/callback_test.agi|4989666555444") in new stack
May 24 11:01:24 VERBOSE[24223] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/callback/callback_test.agi
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_request: callback/callback_test.agi
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_channel: Zap/4-1
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_language: en
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_type: Zap
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_uniqueid: mgw1-1148461277.150041
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_callerid: 4989666555444
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_calleridname: unknown
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_callingpres: 33
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_callingani2: 0
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_callington: 17
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_callingtns: 0
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_dnid: 12345
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_rdnis: unknown
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_context: from-external
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_extension: 123456
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_priority: 5
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_enhanced: 0.0
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> agi_accountcode: 
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> 
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Rx << 20060524110124267210000
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> 510 Invalid or unknown command
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Rx << EXEC Hangup
May 24 11:01:24 VERBOSE[24223] logger.c:     -- AGI Script Executing Application: (Hangup) Options: ((null))
May 24 11:01:24 VERBOSE[24223] logger.c: AGI Tx >> 200 result=-1
May 24 11:01:29 VERBOSE[24223] logger.c:     -- AGI Script callback/callback_test.agi completed, returning 0
May 24 11:01:29 VERBOSE[24223] logger.c:     -- Hungup 'Zap/4-1'


# Call 2(Leg 1)
# initiated by call-file:
May 24 11:01:29 VERBOSE[24261] logger.c:     -- Attempting call on ZAP/G1/004989666555444 for 4989666555444@callback_test:1 (Retry 1)
May 24 11:01:29 VERBOSE[24261] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
May 24 11:01:31 VERBOSE[24261] logger.c:        > Channel Zap/121-1 was answered.

# Call 2(Leg 2)
# context callback_test:
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing NoOp("Zap/121-1", "CALLERIDNUM=123456 CALLERIDNAME=MGW1") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing SetCIDNum("Zap/121-1", "4989666555444") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing SetCIDName("Zap/121-1", "4989666555444") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing NoOp("Zap/121-1", "CALLERIDNUM=4989666555444 CALLERIDNAME=4989666555444") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing Goto("Zap/121-1", "callback_disa|s|1") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Goto (callback_disa,s,1)
# context callback_disa:
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing NoOp("Zap/121-1", "CALLERIDNUM=4989666555444 CALLERIDNAME=4989666555444") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing Set("Zap/121-1", "TIMEOUT(digit)=5") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Digit timeout set to 5
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing Set("Zap/121-1", "TIMEOUT(response)=15") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Response timeout set to 15
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Executing Authenticate("Zap/121-1", "321") in new stack
May 24 11:01:31 VERBOSE[24261] logger.c:     -- Playing 'agent-pass' (language 'en')
May 24 11:01:36 VERBOSE[24261] logger.c:     -- Playing 'auth-thankyou' (language 'en')
May 24 11:01:37 VERBOSE[24261] logger.c:     -- Executing DISA("Zap/121-1", "no-password|callback_disa_auth") in new stack
# Callerid has suddenly changed to the original cli in the next context without dialplan-intervention
# context callback_disa_auth:
May 24 11:01:46 VERBOSE[24261] logger.c:     -- Executing NoOp("Zap/121-1", "CALLERIDNUM=123456 CALLERIDNAME=4989666555444") in new stack
May 24 11:01:46 VERBOSE[24261] logger.c:     -- Executing Dial("Zap/121-1", "Zap/G1/004989111222333") in new stack
May 24 11:01:46 VERBOSE[24261] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
May 24 11:01:46 VERBOSE[24261] logger.c:     -- Called G1/004989111222333
May 24 11:01:48 VERBOSE[24261] logger.c:     -- Zap/119-1 is proceeding passing it to Zap/121-1
May 24 11:01:48 VERBOSE[24261] logger.c:     -- Zap/119-1 is ringing
May 24 11:01:49 VERBOSE[24261] logger.c:     -- Zap/119-1 answered Zap/121-1
May 24 11:01:49 VERBOSE[24261] logger.c:     -- Attempting native bridge of Zap/121-1 and Zap/119-1
May 24 11:01:54 VERBOSE[24261] logger.c:     -- Hungup 'Zap/119-1'
May 24 11:01:54 VERBOSE[24261] logger.c:   == Spawn extension (callback_disa_auth, 004989111222333, 2) exited non-zero on 'Zap/121-1'
May 24 11:01:54 VERBOSE[24261] logger.c:     -- Hungup 'Zap/121-1'