Jun 6 21:08:29 DEBUG[6868]: chan_sip.c:6172 check_user_full: Setting NAT on RTP to 524288
Jun 6 21:08:29 DEBUG[6868]: chan_sip.c:1051 __sip_ack: Stopping retransmission on '08F040E1-4615-4F1F-95A7-E7FB069F1DBC@10.16.46.8' of Response 50556: Found
Jun 6 21:08:29 DEBUG[6868]: chan_sip.c:6172 check_user_full: Setting NAT on RTP to 524288
Jun 6 21:08:29 DEBUG[6868]: chan_sip.c:8899 handle_request_invite: Checking SIP call limits for device COP
Jun 6 21:08:29 DEBUG[6868]: chan_sip.c:5287 build_route: build_route: Contact hop: <sip:COP@10.16.46.8:5060>
-- Executing Ringing("SIP/COP-35cf", "") in new stack
-- Executing Wait("SIP/COP-35cf", "2") in new stack
-- Executing VoiceMailMain("SIP/COP-35cf", "") in new stack
Jun 6 21:08:31 DEBUG[6868]: channel.c:1274 ast_settimeout: Scheduling timer at 160 sample intervals
-- Playing 'vm-login' (language 'en')
Jun 6 21:08:31 DEBUG[6868]: chan_sip.c:1051 __sip_ack: Stopping retransmission on '08F040E1-4615-4F1F-95A7-E7FB069F1DBC@10.16.46.8' of Response 50557: Found
Jun 6 21:08:46 DEBUG[6868]: channel.c:1274 ast_settimeout: Scheduling timer at 0 sample intervals
Jun 6 21:08:46 WARNING[6868]: app_voicemail.c:4505 vm_authenticate: Couldn't read username
Jun 6 21:08:46 DEBUG[6868]: pbx.c:2342 __ast_pbx_run: Extension 7000, priority 3 returned normally even though call was hung up
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '"Claude-Olivier Pitt" <7002>'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '7002'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '7000'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is 'sip'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is 'SIP/COP-35cf'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '(null)'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is 'VoiceMailMain'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '(null)'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '2005-06-06 21:08:29'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '2005-06-06 21:08:29'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '2005-06-06 21:08:29'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '17'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '15'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '(null)'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '1118106509.0'
Jun 6 21:08:46 DEBUG[6868]: pbx.c:1465 pbx_substitute_variables_helper_full: Function result is '(null)'
Jun 6 21:08:46 DEBUG[6868]: chan_sip.c:2052 sip_hangup: update_user_counter(COP) - decrement inUse counter
Jun 6 21:10:08 NOTICE[6868]: chan_sip.c:9318 handle_request_register: Registration from 'SIPURA-1001-1 <sip:SIPURA-1001-1@10.16.46.9>' failed for '10.16.46.10'
Jun 6 21:10:23 DEBUG[6868]: chan_sip.c:979 __sip_autodestruct: Auto destroying call 'a6aebde3-5d05563d@10.16.46.10'