[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Executing [55555@localtest_in:1] Macro("SIP/2227654902-081800d8", "beginout") in new stack
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Executing [s@macro-beginout:1] UserEvent("SIP/2227654902-081800d8", "CALL|aclocal=55555=2227654902=20071017-114212=1192614132.38") in new stack
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Executing [55555@localtest_in:2] Goto("SIP/2227654902-081800d8", "card_in|1|1") in new stack
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Goto (card_in,1,1)
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Executing [1@card_in:1] Answer("SIP/2227654902-081800d8", "") in new stack
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Executing [1@card_in:2] Read("SIP/2227654902-081800d8", "TEAS_PIN||9") in new stack
[Oct 17 11:42:12] VERBOSE[29284] logger.c: -- Accepting a maximum of 9 digits.
[Oct 17 11:42:14] DTMF[29284] channel.c: DTMF end '1' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:14] DTMF[29284] channel.c: DTMF end '2' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:15] DTMF[29284] channel.c: DTMF end '3' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:15] DTMF[29284] channel.c: DTMF end '4' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:16] DTMF[29284] channel.c: DTMF end '5' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:16] DTMF[29284] channel.c: DTMF end '6' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:16] DTMF[29284] channel.c: DTMF end '7' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:16] DTMF[29284] channel.c: DTMF end '8' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:17] DTMF[29284] channel.c: DTMF end '9' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:17] DTMF[29284] channel.c: DTMF end '#' received on SIP/2227654902-081800d8, duration 100 ms
[Oct 17 11:42:22] VERBOSE[29284] logger.c: -- User entered nothing.
[Oct 17 11:42:22] VERBOSE[29284] logger.c: -- Executing [1@card_in:3] NoOp("SIP/2227654902-081800d8", "") in new stack
[Oct 17 11:42:22] VERBOSE[29284] logger.c: -- Executing [1@card_in:4] Hangup("SIP/2227654902-081800d8", "") in new stack
[Oct 17 11:42:22] VERBOSE[29284] logger.c: == Spawn extension (card_in, 1, 4) exited non-zero on 'SIP/2227654902-081800d8'
[Oct 17 11:42:22] VERBOSE[29284] logger.c: -- Executing [h@card_in:1] NoOp("SIP/2227654902-081800d8", "err HANGUP") in new stack