[May 23 09:59:56] -- SIP/2037-000044ee is ringing [May 23 09:59:56] -- SIP/2050-000044ef is ringing [May 23 09:59:56] -- SIP/2036-000044ed connected line has changed. Saving it until answer for SIP/vproxy2-000044e3 [May 23 09:59:56] -- SIP/2036-000044ed answered SIP/vproxy2-000044e3 [May 23 09:59:56] -- Playing 'digits/40.gsm' (language 'en') [May 23 09:59:56] -- Executing [s@macro-record:1] AGI("SIP/2036-000044ed", "macrohelper.agi") in new stack [May 23 09:59:56] -- Launched AGI Script /var/asterisk/agi-bin/macrohelper.agi [May 23 09:59:56] -- AGI Script macrohelper.agi completed, returning 0 [May 23 09:59:56] -- Executing [s@macro-record:2] ExecIf("SIP/2036-000044ed", "0?Monitor(wav,inbound--13377xxxxx-60857xxxxx,bm)") in new stack [May 23 09:59:56] -- Executing [s@macro-record:3] ExecIf("SIP/2036-000044ed", "0?Macro(ishuman)") in new stack [May 23 09:59:56] -- Playing 'vm-prev.gsm' (language 'en') [May 23 09:59:57] -- Playing 'digits/5.gsm' (language 'en') [May 23 09:59:57] -- SIP/2000-000044d7 connected line has changed. Saving it until answer for SIP/vproxy1-000044d6 [May 23 09:59:57] -- SIP/2000-000044d7 answered SIP/vproxy1-000044d6 [May 23 09:59:57] -- Executing [s@macro-record:1] AGI("SIP/2000-000044d7", "macrohelper.agi") in new stack [May 23 09:59:57] -- Launched AGI Script /var/asterisk/agi-bin/macrohelper.agi [May 23 09:59:58] -- Playing 'vm-deleted.gsm' (language 'en') [May 23 09:59:58] -- Playing 'digits/p-m.gsm' (language 'en') [May 23 09:59:58] -- AGI Script macrohelper.agi completed, returning 0 [May 23 09:59:58] -- Executing [s@macro-record:2] ExecIf("SIP/2000-000044d7", "0?Monitor(wav,inbound--13377xxxxx-31282xxxxx,bm)") in new stack [May 23 09:59:58] -- Executing [s@macro-record:3] ExecIf("SIP/2000-000044d7", "0?Macro(ishuman)") in new stack [May 23 09:59:58] -- Playing '/var/spool/asterisk/voicemail/default/1035/Old/msg0000.slin' (language 'en') [May 23 09:59:59] -- Playing 'vm-prev.gsm' (language 'en') [May 23 09:59:59] -- SIP/vproxy1-000044d3 answered SIP/3431-000044d2 [May 23 10:00:00] -- Playing 'vm-goodbye.gsm' (language 'en') [May 23 10:00:00] WARNING[5687]: file.c:766 ast_readaudio_callback: Failed to write frame [May 23 10:00:00] == Spawn extension (outbound, 20, 1) exited non-zero on 'SIP/2115-000044b2' [May 23 10:00:02] -- Playing 'vm-extension.gsm' (language 'en') [May 23 10:00:06] == Spawn extension (macro-dialoutbound, s, 14) exited non-zero on 'SIP/3014-000044c1' in macro 'dialoutbound' [May 23 10:00:06] == Spawn extension (outbound, 8166783673, 5) exited non-zero on 'SIP/3014-000044c1' [May 23 10:00:07] -- Playing '/var/spool/asterisk/voicemail/default/1032/greet.slin' (language 'en') [May 23 10:00:08] == Parsing '/var/spool/asterisk/voicemail/default/1035/Old/msg0000.txt': [May 23 10:00:08] == Found [May 23 10:00:08] -- Playing 'vm-forwardoptions.gsm' (language 'en') [May 23 10:00:10] -- Playing 'beep.gsm' (language 'en') [May 23 10:00:11] -- x=0, open writing: /var/spool/asterisk/voicemail/default/1035/Old/msg0000-prepend format: wav, 0x95d96b0 [May 23 10:00:11] ERROR[10455]: lock.c:117 __ast_pthread_mutex_destroy: astobj2.c line 265 (internal_ao2_ref): Error: attempt to destroy locked mutex '&obj->priv_data.lock'. [May 23 10:00:11] ERROR[10455]: lock.c:121 __ast_pthread_mutex_destroy: (null) line 0 ((null)): Error: '&obj->priv_data.lock' was locked here. [May 23 10:00:17] NOTICE[10455]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 23 10:00:17] NOTICE[10455]: chan_sip.c:24968 handle_request_register: Registration from '' failed for '172.20.16.6:5060' - No matching peer found [May 23 10:00:20] -- User ended message by pressing # [May 23 10:00:20] > Recording Format: sfmts=wav, prependfile /var/spool/asterisk/voicemail/default/1035/Old/msg0000-prepend, recordfile /var/spool/asterisk/voicemail/default/1035/Old/msg0000 [May 23 10:00:20] == Saving '/var/spool/asterisk/voicemail/default/1035/Old/msg0000.txt': [May 23 10:00:20] NOTICE[5765]: app_voicemail.c:5324 copy_message: Copying message from 1035@default to 1032@default [May 23 10:00:20] -- Playing 'vm-msgsaved.gsm' (language 'en') [May 23 10:00:22] -- Playing 'vm-advopts.gsm' (language 'en') [May 23 10:00:22] == Using SIP RTP TOS bits 184 [May 23 10:00:22] == Using SIP RTP CoS mark 5 [May 23 10:00:22] -- Executing [2231@outbound:1] NoOp("SIP/2115-000044f0", "") in new stack [May 23 10:00:22] -- Executing [2231@outbound:2] Gosub("SIP/2115-000044f0", "macro-dialext,s,1(2231,4,0,0)") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:1] Set("SIP/2115-000044f0", "LIMIT_WARNING_FILE=beep") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:2] Set("SIP/2115-000044f0", "LIMIT_PLAYAUDIO_CALLEE=yes") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:3] NoOp("SIP/2115-000044f0", "") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:4] ExecIf("SIP/2115-000044f0", "0?Set(ARG2=0)") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:5] ExecIf("SIP/2115-000044f0", "0?Set(ARG3=0)") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:6] ExecIf("SIP/2115-000044f0", "0?Set(ARG4=0)") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:7] ExecIf("SIP/2115-000044f0", "1?Set(ARG5=0)") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:8] NoOp("SIP/2115-000044f0", "Wed May 23 10:00:22 2012") in new stack [May 23 10:00:22] -- Executing [s@macro-dialext:9] Dial("SIP/2115-000044f0", "SIP/2231,24,wWrt") in new stack [May 23 10:00:22] == Using SIP RTP TOS bits 184 [May 23 10:00:22] == Using SIP RTP CoS mark 5 [May 23 10:00:22] -- Called SIP/2231 [May 23 10:00:22] -- Playing 'vm-undeleted.gsm' (language 'en') [May 23 10:00:22] -- Playing 'vm-nomore.gsm' (language 'en') skipperbuds*CLI> Disconnected from Asterisk server