[Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:1] Set("IAX2/mgrs-4e1-9655", "CN=7727799") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:2] Set("IAX2/mgrs-4e1-9655", "IAXID=100000140889") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:3] GotoIf("IAX2/mgrs-4e1-9655", "0?getid:alldone") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:10] UserEvent("IAX2/mgrs-4e1-9655", "OLOLO,Channel: IAX2/mgrs-4e1-9655,IAXID: 100000140889") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:11] MixMonitor("IAX2/mgrs-4e1-9655", "IN-7727799#100000140889-23072009:08:10:53-4959489231-1248322253.783.gsm") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799#100000140889@custom-pstn:12] Goto("IAX2/mgrs-4e1-9655", "from-pstn,7727799,1") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:1] Set("IAX2/mgrs-4e1-9655", "__FROM_DID=7727799") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:2] Gosub("IAX2/mgrs-4e1-9655", "app-blacklist-check,s,1") in new stack [Jul 23 08:10:53] DEBUG[32500] app_stack.c: Channel IAX2/mgrs-4e1-9655 has no datastore, so we're allocating one. [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@app-blacklist-check:1] GotoIf("IAX2/mgrs-4e1-9655", "0?blacklisted") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@app-blacklist-check:2] Return("IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:3] ExecIf("IAX2/mgrs-4e1-9655", "1 ?Set(CALLERID(name)=4959489231)") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:4] Set("IAX2/mgrs-4e1-9655", "__CALLINGPRES_SV=allowed") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:5] Set("IAX2/mgrs-4e1-9655", "CALLERPRES()=allowed_not_screened") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [7727799@from-pstn:6] Goto("IAX2/mgrs-4e1-9655", "ext-queues,00000,1") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:1] Macro("IAX2/mgrs-4e1-9655", "user-callerid,") in new stack [Jul 23 08:10:53] VERBOSE[32501] logger.c: == Begin MixMonitor Recording IAX2/mgrs-4e1-9655 [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:1] Set("IAX2/mgrs-4e1-9655", "AMPUSER=4959489231") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("IAX2/mgrs-4e1-9655", "0?report") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("IAX2/mgrs-4e1-9655", "1?Set(REALCALLERIDNUM=4959489231)") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:4] Set("IAX2/mgrs-4e1-9655", "AMPUSER=") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:5] Set("IAX2/mgrs-4e1-9655", "AMPUSERCIDNAME=") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("IAX2/mgrs-4e1-9655", "1?report") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("IAX2/mgrs-4e1-9655", "0?continue") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:12] Set("IAX2/mgrs-4e1-9655", "__TTL=64") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("IAX2/mgrs-4e1-9655", "1?continue") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("IAX2/mgrs-4e1-9655", "Using CallerID "4959489231" <4959489231>") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:2] Answer("IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:3] Set("IAX2/mgrs-4e1-9655", "__BLKVM_OVERRIDE=BLKVM/00000/IAX2/mgrs-4e1-9655") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:4] Set("IAX2/mgrs-4e1-9655", "__BLKVM_BASE=00000") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:5] Set("IAX2/mgrs-4e1-9655", "DB(BLKVM/00000/IAX2/mgrs-4e1-9655)=TRUE") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:6] ExecIf("IAX2/mgrs-4e1-9655", "1?Set(_DIAL_OPTIONS=trM(auto-blkvm))") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:7] Set("IAX2/mgrs-4e1-9655", "__NODEST=00000") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:8] Set("IAX2/mgrs-4e1-9655", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q00000-20090723-081053-1248322253.783") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:9] Set("IAX2/mgrs-4e1-9655", "__MOHCLASS=default") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [00000@ext-queues:10] Queue("IAX2/mgrs-4e1-9655", "00000,t,,") in new stack [Jul 23 08:10:53] DEBUG[32500] app_queue.c: Queue '00000' Join, Channel 'IAX2/mgrs-4e1-9655', Position '1' [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Started music on hold, class 'default', on IAX2/mgrs-4e1-9655 [Jul 23 08:10:53] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:10:53] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:10:53] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format slin [Jul 23 08:10:53] DEBUG[32500] res_musiconhold.c: IAX2/mgrs-4e1-9655 Opened file 0 '/var/lib/asterisk/mohmp3//orig_wav_BG2' [Jul 23 08:10:53] DEBUG[31941] channel.c: Soft-Hanging up channel 'IAX2/mgrs-4e1-9655' [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Stopped music on hold on IAX2/mgrs-4e1-9655 [Jul 23 08:10:53] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format ulaw [Jul 23 08:10:53] DEBUG[32500] app_queue.c: Queue '00000' Leave, Channel 'IAX2/mgrs-4e1-9655' [Jul 23 08:10:53] DEBUG[32500] pbx.c: Spawn extension (from-internal,91110,1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:10:53] VERBOSE[32500] logger.c: == Spawn extension (from-internal, 91110, 1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:1] Macro("IAX2/mgrs-4e1-9655", "user-callerid,") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:1] Set("IAX2/mgrs-4e1-9655", "AMPUSER=4959489231") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("IAX2/mgrs-4e1-9655", "0?report") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("IAX2/mgrs-4e1-9655", "0?Set(REALCALLERIDNUM=4959489231)") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:4] Set("IAX2/mgrs-4e1-9655", "AMPUSER=") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:5] Set("IAX2/mgrs-4e1-9655", "AMPUSERCIDNAME=") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("IAX2/mgrs-4e1-9655", "1?report") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("IAX2/mgrs-4e1-9655", "0?continue") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:12] Set("IAX2/mgrs-4e1-9655", "__TTL=63") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("IAX2/mgrs-4e1-9655", "1?continue") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("IAX2/mgrs-4e1-9655", "Using CallerID "4959489231" <4959489231>") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:2] Answer("IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:3] Set("IAX2/mgrs-4e1-9655", "__BLKVM_OVERRIDE=BLKVM/91110/IAX2/mgrs-4e1-9655") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:4] Set("IAX2/mgrs-4e1-9655", "__BLKVM_BASE=91110") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:5] Set("IAX2/mgrs-4e1-9655", "DB(BLKVM/91110/IAX2/mgrs-4e1-9655)=TRUE") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:6] ExecIf("IAX2/mgrs-4e1-9655", "0?Set(_DIAL_OPTIONS=trM(auto-blkvm)M(auto-blkvm))") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:7] Set("IAX2/mgrs-4e1-9655", "__NODEST=91110") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:8] Set("IAX2/mgrs-4e1-9655", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q91110-20090723-081053-1248322253.783") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:9] Set("IAX2/mgrs-4e1-9655", "__MOHCLASS=default") in new stack [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:10] Queue("IAX2/mgrs-4e1-9655", "91110,t,,") in new stack [Jul 23 08:10:53] DEBUG[32500] app_queue.c: Queue '91110' Join, Channel 'IAX2/mgrs-4e1-9655', Position '1' [Jul 23 08:10:53] VERBOSE[32500] logger.c: -- Started music on hold, class 'default', on IAX2/mgrs-4e1-9655 [Jul 23 08:10:53] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:10:53] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:10:53] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format slin [Jul 23 08:10:53] DEBUG[32500] res_musiconhold.c: IAX2/mgrs-4e1-9655 Opened file 0 '/var/lib/asterisk/mohmp3//orig_wav_BG2' [Jul 23 08:10:58] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:10:58] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:11:04] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:11:04] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:11:04] DEBUG[31941] channel.c: Soft-Hanging up channel 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:04] VERBOSE[32500] logger.c: -- Stopped music on hold on IAX2/mgrs-4e1-9655 [Jul 23 08:11:04] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format ulaw [Jul 23 08:11:04] DEBUG[32500] app_queue.c: Queue '91110' Leave, Channel 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:04] DEBUG[32500] pbx.c: Spawn extension (from-internal,591110,1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:04] VERBOSE[32500] logger.c: == Spawn extension (from-internal, 591110, 1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:04] VERBOSE[32500] logger.c: -- Executing [591110@from-internal:1] Answer("IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:11:04] VERBOSE[32500] logger.c: -- Executing [591110@from-internal:2] Wait("IAX2/mgrs-4e1-9655", "1") in new stack [Jul 23 08:11:06] VERBOSE[32500] logger.c: -- Executing [591110@from-internal:3] GotoIf("IAX2/mgrs-4e1-9655", "0?skip") in new stack [Jul 23 08:11:06] VERBOSE[32500] logger.c: -- Executing [591110@from-internal:4] MixMonitor("IAX2/mgrs-4e1-9655", "CONF-591110-23072009:08:11:06-4959489231-1248322253.783.gsm") in new stack [Jul 23 08:11:06] VERBOSE[32500] logger.c: -- Executing [591110@from-internal:5] MeetMe("IAX2/mgrs-4e1-9655", "591110,1pxq") in new stack [Jul 23 08:11:06] VERBOSE[32514] logger.c: == Begin MixMonitor Recording IAX2/mgrs-4e1-9655 [Jul 23 08:11:06] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format slin [Jul 23 08:11:06] DEBUG[32500] channel.c: Set channel IAX2/mgrs-4e1-9655 to read format slin [Jul 23 08:11:06] DEBUG[32500] app_meetme.c: Placed channel IAX2/mgrs-4e1-9655 in DAHDI conf 1020 [Jul 23 08:11:24] DEBUG[31941] channel.c: Soft-Hanging up channel 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:24] DEBUG[32500] pbx.c: Spawn extension (from-internal,91110,1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:24] VERBOSE[32500] logger.c: == Spawn extension (from-internal, 91110, 1) exited non-zero on 'IAX2/mgrs-4e1-9655' [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:1] Macro("IAX2/mgrs-4e1-9655", "user-callerid,") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:1] Set("IAX2/mgrs-4e1-9655", "AMPUSER=4959489231") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("IAX2/mgrs-4e1-9655", "0?report") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("IAX2/mgrs-4e1-9655", "0?Set(REALCALLERIDNUM=4959489231)") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:4] Set("IAX2/mgrs-4e1-9655", "AMPUSER=") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:5] Set("IAX2/mgrs-4e1-9655", "AMPUSERCIDNAME=") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("IAX2/mgrs-4e1-9655", "1?report") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("IAX2/mgrs-4e1-9655", "0?continue") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:12] Set("IAX2/mgrs-4e1-9655", "__TTL=62") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("IAX2/mgrs-4e1-9655", "1?continue") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("IAX2/mgrs-4e1-9655", "Using CallerID "4959489231" <4959489231>") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:2] Answer("IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:3] Set("IAX2/mgrs-4e1-9655", "__BLKVM_OVERRIDE=BLKVM/91110/IAX2/mgrs-4e1-9655") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:4] Set("IAX2/mgrs-4e1-9655", "__BLKVM_BASE=91110") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:5] Set("IAX2/mgrs-4e1-9655", "DB(BLKVM/91110/IAX2/mgrs-4e1-9655)=TRUE") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:6] ExecIf("IAX2/mgrs-4e1-9655", "0?Set(_DIAL_OPTIONS=trM(auto-blkvm)M(auto-blkvm))") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:7] Set("IAX2/mgrs-4e1-9655", "__NODEST=91110") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:8] Set("IAX2/mgrs-4e1-9655", "MONITOR_FILENAME=/var/spool/asterisk/monitor/q91110-20090723-081124-1248322253.783") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:9] Set("IAX2/mgrs-4e1-9655", "__MOHCLASS=default") in new stack [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Executing [91110@from-internal:10] Queue("IAX2/mgrs-4e1-9655", "91110,t,,") in new stack [Jul 23 08:11:24] DEBUG[32500] app_queue.c: Queue '91110' Join, Channel 'IAX2/mgrs-4e1-9655', Position '1' [Jul 23 08:11:24] VERBOSE[32500] logger.c: -- Started music on hold, class 'default', on IAX2/mgrs-4e1-9655 [Jul 23 08:11:24] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:11:24] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:11:24] DEBUG[32500] res_musiconhold.c: IAX2/mgrs-4e1-9655 Opened file 0 '/var/lib/asterisk/mohmp3//orig_wav_BG2' [Jul 23 08:11:29] DEBUG[32500] app_queue.c: It's our turn (IAX2/mgrs-4e1-9655). [Jul 23 08:11:29] DEBUG[32500] app_queue.c: IAX2/mgrs-4e1-9655 is trying to call a queue member. [Jul 23 08:11:33] VERBOSE[31941] logger.c: -- Stopped music on hold on IAX2/mgrs-4e1-9655 [Jul 23 08:11:33] DEBUG[31941] channel.c: Planning to masquerade channel IAX2/mgrs-4e1-9655 into the structure of Bridge/IAX2/mgrs-4e1-9655 [Jul 23 08:11:33] DEBUG[31941] channel.c: Done planning to masquerade channel IAX2/mgrs-4e1-9655 into the structure of Bridge/IAX2/mgrs-4e1-9655 [Jul 23 08:11:33] DEBUG[31941] channel.c: Set channel IAX2/mgrs-4e1-9655 to write format slin [Jul 23 08:11:33] DEBUG[31941] channel.c: Set channel IAX2/mgrs-4e1-9655 to read format slin [Jul 23 08:11:33] DEBUG[31941] channel.c: Putting channel IAX2/mgrs-4e1-9655 in 64/64 formats [Jul 23 08:11:33] DEBUG[31941] channel.c: Released clone lock on 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] DEBUG[31941] channel.c: Done Masquerading IAX2/mgrs-4e1-9655 (6) [Jul 23 08:11:33] DEBUG[32500] app_queue.c: Queue '91110' Leave, Channel 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] DEBUG[32500] pbx.c: Spawn extension (from-internal,91110,10) exited non-zero on 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] VERBOSE[32500] logger.c: == Spawn extension (from-internal, 91110, 10) exited non-zero on 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] DEBUG[32500] channel.c: Soft-Hanging up channel 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [h@from-internal:1] Macro("Bridge/IAX2/mgrs-4e1-9655", "hangupcall") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("Bridge/IAX2/mgrs-4e1-9655", "w") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("Bridge/IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("Bridge/IAX2/mgrs-4e1-9655", "1?skiprg") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("Bridge/IAX2/mgrs-4e1-9655", "1?skipblkvm") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("Bridge/IAX2/mgrs-4e1-9655", "1?theend") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("Bridge/IAX2/mgrs-4e1-9655", "") in new stack [Jul 23 08:11:33] VERBOSE[32500] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Bridge/IAX2/mgrs-4e1-9655' in macro 'hangupcall' [Jul 23 08:11:33] DEBUG[32500] pbx.c: Spawn extension (from-internal,h,1) exited non-zero on 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] VERBOSE[32500] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] DEBUG[32500] channel.c: Hanging up zombie 'Bridge/IAX2/mgrs-4e1-9655' [Jul 23 08:11:33] VERBOSE[32501] logger.c: == End MixMonitor Recording IAX2/mgrs-4e1-9655 [Jul 23 08:11:33] VERBOSE[32514] logger.c: == End MixMonitor Recording IAX2/mgrs-4e1-9655 [Jul 23 08:11:33] VERBOSE[23945] logger.c: -- Channel 'IAX2/mgrs-4e1-9655' ready to transfer [Jul 23 08:11:33] VERBOSE[23945] logger.c: -- Releasing IAX2/mgrs-4e1-9655 and IAX2/officeUsievicha-16276