testasterisk11*CLI> core set debug 5
Core debug was 0 and is now 5
[Sep  4 13:50:00] DEBUG[5502]: res_xmpp.c:2988 xmpp_pak_message: XMPP client 'testaccount' received a message
[Sep  4 13:50:00] DEBUG[5502]: res_xmpp.c:3029 xmpp_pak_message: Deleted 2 messages for client testaccount from JID nengelberth@jabber.metalink.net
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Verbose'
    -- Executing [s@xmpp-incoming:1] Verbose("Message/ast_msg_queue", "2,Received XMPP message") in new stack
[Sep  4 13:50:00] DEBUG[5502]: res_xmpp.c:3494 xmpp_client_receive: XML parsing successful
  == Received XMPP message
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'xmpp:nengelberth@jabber.metalink.net/15f51291'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Verbose'
    -- Executing [s@xmpp-incoming:2] Verbose("Message/ast_msg_queue", "2,Received message from xmpp:nengelberth@jabber.metalink.net/15f51291") in new stack
  == Received message from xmpp:nengelberth@jabber.metalink.net/15f51291
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'why is the moon'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Verbose'
    -- Executing [s@xmpp-incoming:3] Verbose("Message/ast_msg_queue", "2,Received message body: why is the moon") in new stack
  == Received message body: why is the moon
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'why is the moon'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4306 pbx_substitute_variables_helper_full: Expression result is '1'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'GotoIf'
    -- Executing [s@xmpp-incoming:4] GotoIf("Message/ast_msg_queue", "1?message,1") in new stack
    -- Goto (xmpp-incoming,message,1)
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Verbose'
    -- Executing [message@xmpp-incoming:1] Verbose("Message/ast_msg_queue", "2,Processing XMPP message") in new stack
  == Processing XMPP message
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'xmpp:nengelberth@jabber.metalink.net/15f51291'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Set'
    -- Executing [message@xmpp-incoming:2] Set("Message/ast_msg_queue", "INC_USER=xmpp:nengelberth@jabber.metalink.net/15f51291") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4005 ast_str_substitute_variables_full: Evaluating 'INC_USER' (from 'INC_USER}' len 8)
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_USER' is 'xmpp:nengelberth@jabber.metalink.net/15f51291'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'nengelberth@jabber.metalink.net/15f51291'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Set'
    -- Executing [message@xmpp-incoming:3] Set("Message/ast_msg_queue", "INC_REPLY=nengelberth@jabber.metalink.net/15f51291") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4005 ast_str_substitute_variables_full: Evaluating 'INC_REPLY' (from 'INC_REPLY}' len 9)
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_REPLY' is 'nengelberth@jabber.metalink.net/15f51291'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'nengelberth@jabber.metalink.net'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Set'
    -- Executing [message@xmpp-incoming:4] Set("Message/ast_msg_queue", "INC_REPLY=nengelberth@jabber.metalink.net") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_REPLY' is 'nengelberth@jabber.metalink.net'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: res_xmpp.c:1883 acf_jabberreceive_read: Waiting for an XMPP message from nengelberth@jabber.metalink.net
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'why is the moon'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Set'
    -- Executing [message@xmpp-incoming:5] Set("Message/ast_msg_queue", "INC_MSG=why is the moon") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4005 ast_str_substitute_variables_full: Evaluating 'INC_MSG' (from 'INC_MSG}' len 7)
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_MSG' is 'why is the moon'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4238 pbx_substitute_variables_helper_full: Function result is 'why'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Set'
    -- Executing [message@xmpp-incoming:6] Set("Message/ast_msg_queue", "INC_APP=why") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_APP' is 'why'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4306 pbx_substitute_variables_helper_full: Expression result is '0'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'GotoIf'
    -- Executing [message@xmpp-incoming:7] GotoIf("Message/ast_msg_queue", "0?voicemail,1") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:11309 pbx_builtin_gotoif: Not taking any branch
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:3419 ast_str_retrieve_variable: Result of 'INC_REPLY' is 'nengelberth@jabber.metalink.net'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'JabberSend'
    -- Executing [message@xmpp-incoming:8] JabberSend("Message/ast_msg_queue", "testaccount,nengelberth@jabber.metalink.net,Sorry, I do not understand your request") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Hangup'
    -- Executing [message@xmpp-incoming:9] Hangup("Message/ast_msg_queue", "") in new stack
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: channel.c:2639 ast_softhangup_nolock: Soft-Hanging up channel 'Message/ast_msg_queue'
[Sep  4 13:50:00] DEBUG[5405][C-00000000]: pbx.c:6090 __ast_pbx_run: Spawn extension (xmpp-incoming,message,9) exited non-zero on 'Message/ast_msg_queue'
  == Spawn extension (xmpp-incoming, message, 9) exited non-zero on 'Message/ast_msg_queue'
[Sep  4 13:50:04] DEBUG[5502]: res_xmpp.c:2988 xmpp_pak_message: XMPP client 'testaccount' received a message
[Sep  4 13:50:04] DEBUG[5502]: res_xmpp.c:3029 xmpp_pak_message: Deleted 0 messages for client testaccount from JID nengelberth@jabber.metalink.net
[Sep  4 13:50:04] DEBUG[5502]: res_xmpp.c:3494 xmpp_client_receive: XML parsing successful
[Sep  4 13:50:04] DEBUG[5405][C-00000000]: pbx.c:4410 pbx_extension_helper: Launching 'Verbose'
    -- Executing [s@xmpp-incoming:1] Verbose("Message/ast_msg_queue", "2,Received XMPP message") in new stack
  == Received XMPP message
[Sep  4 13:50:04] DEBUG[5405][C-00000000]: pbx.c:6065 __ast_pbx_run: Extension s, priority 1 returned normally even though call was hung up