[root@switch log]# grep -inr "9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a" freeswitch.log.2017-10-09-22-36-03.1
span style="color: cornflowerblue; font-weight: bold;"> 58295:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [NOTICE] switch_channel.c:1055 New Channel sofia/default/6501303426@81.95.119.150 [9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a]
58296:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58297:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_NEW
58298:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58299:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] sofia.c:8834 sofia/default/6501303426@81.95.119.150 receiving invite from 173.195.0.12:62235 version: 1.4.14 64bit
58302:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] switch_core_state_machine.c:491 (sofia/default/6501303426@81.95.119.150) State NEW
58303:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:55.770733 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58304:2017-10-09 14:56:55.770733 [DEBUG] sofia.c:2067 detaching session 9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58305:2017-10-09 14:56:56.010751 [DEBUG] sofia.c:2175 Re-attaching to session 9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58306:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.010751 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58307:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.010751 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58308:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.030757 [DEBUG] sofia.c:8834 sofia/default/6501303426@81.95.119.150 receiving invite from 173.195.0.12:62235 version: 1.4.14 64bit
58310:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] sofia.c:10100 Setting NAT mode based on via received
58311:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [received][100]
58312:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] sofia.c:6624 Remote SDP:
58313:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a v=0
58314:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a o=- 968566 968636 IN IP4 100.68.87.30
58315:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a s=eyeBeam
58316:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a c=IN IP4 100.68.87.30
58317:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a t=0 0
58318:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a m=audio 9224 RTP/AVP 100 6 0 8 3 18 5 101
58319:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:100 speex/16000
58320:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:101 telephone-event/8000
58321:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=fmtp:101 0-15
58322:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=alt:1 1 : C8EA855A 0000008B 100.68.87.30 9224
58323:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58324:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [speex:100:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
58325:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [speex:100:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
58326:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [speex:100:16000:20:0:1]/[G729:18:8000:20:8000:1]
58327:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
58328:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
58329:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:6:16000:20:0:1]/[G729:18:8000:20:8000:1]
58330:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
58331:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
58332:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3670 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
58333:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
58334:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
58335:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3670 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
58336:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
58337:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
58338:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
58339:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
58340:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
58341:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
58342:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
58343:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
58344:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3670 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
58345:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
58346:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
58347:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [DVI4:5:8000:20:0:1]/[G729:18:8000:20:8000:1]
58348:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3531 Set telephone-event payload to 101
58349:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:2473 Set Codec sofia/default/6501303426@81.95.119.150 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
58350:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_codec.c:111 sofia/default/6501303426@81.95.119.150 Original read codec set to PCMU:0
58351:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_media.c:3861 Set 2833 dtmf send/recv payload to 101
58352:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] sofia.c:6910 (sofia/default/6501303426@81.95.119.150) State Change CS_NEW -> CS_INIT
58353:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58354:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_INIT
58355:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:512 (sofia/default/6501303426@81.95.119.150) State INIT
58356:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] mod_sofia.c:87 sofia/default/6501303426@81.95.119.150 SOFIA INIT
58357:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:40 sofia/default/6501303426@81.95.119.150 Standard INIT
58358:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:48 (sofia/default/6501303426@81.95.119.150) State Change CS_INIT -> CS_ROUTING
58359:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58360:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:512 (sofia/default/6501303426@81.95.119.150) State INIT going to sleep
58361:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_ROUTING
58362:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_channel.c:2184 (sofia/default/6501303426@81.95.119.150) Callstate Change DOWN -> RINGING
58363:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:528 (sofia/default/6501303426@81.95.119.150) State ROUTING
58364:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] mod_sofia.c:123 sofia/default/6501303426@81.95.119.150 SOFIA ROUTING
58365:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [DEBUG] switch_core_state_machine.c:166 sofia/default/6501303426@81.95.119.150 Standard ROUTING
58366:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.130770 [INFO] mod_dialplan_xml.c:635 Processing 6501303426 <6501303426>->375295498461 in context default
58367:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 parsing [default->] continue=false
58368:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Regex (PASS) [] destination_number(375295498461) =~ // break=on-false
58369:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(callstart=2017-10-09 18:56:56)
58370:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(call_processed=internal)
58371:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(originated_destination_number=375295498461)
58372:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(hangup_after_bridge=true)
58373:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(continue_on_fail=true)
58374:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(accountid=1)
58375:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(account_type=3)
58376:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(resellerid=0)
58377:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(accountcode=5163162651)
58378:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(call_direction=outbound)
58379:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(calltype=STANDARD)
58380:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action sched_hangup(+3951060 allotted_timeout)
58381:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action log(INFO [Customer info] : [Account code: 5163162651, Rategroup Id: 1, Called number: 375295498461])
58382:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(origination_rates=ID:47093|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.36252|INC:0|RATEGROUP:1|MARKUP:20|ACCID:1)
58383:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action limit(db 5163162651 db_5163162651 1)
58384:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(effective_destination_number=375295498461)
58385:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(termination_rates=ID:1539|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02000|INC:0|TRUNK:2|PROVIDER:1)
58386:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action bridge(sofia/gateway/Voipro_gris/375295498461)
58387:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(effective_destination_number=375295498461)
58388:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action set(termination_rates=ID:1531|CODE:^375.*|DESTINATION:Belarus - Fixed|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02000|INC:0|TRUNK:2|PROVIDER:1)
58389:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a Dialplan: sofia/default/6501303426@81.95.119.150 Action bridge(sofia/gateway/Voipro_gris/375295498461)
58390:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_state_machine.c:216 (sofia/default/6501303426@81.95.119.150) State Change CS_ROUTING -> CS_EXECUTE
58391:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58392:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_state_machine.c:528 (sofia/default/6501303426@81.95.119.150) State ROUTING going to sleep
58393:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_EXECUTE
58394:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_state_machine.c:535 (sofia/default/6501303426@81.95.119.150) State EXECUTE
58395:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_sofia.c:178 sofia/default/6501303426@81.95.119.150 SOFIA EXECUTE
58396:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_core_state_machine.c:258 sofia/default/6501303426@81.95.119.150 Standard EXECUTE
58397:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(callstart=2017-10-09 18:56:56)
58398:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [callstart]=[2017-10-09 18:56:56]
58399:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(call_processed=internal)
58400:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [call_processed]=[internal]
58401:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(originated_destination_number=375295498461)
58402:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [originated_destination_number]=[375295498461]
58403:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(hangup_after_bridge=true)
58404:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [hangup_after_bridge]=[true]
58405:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(continue_on_fail=true)
58406:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [continue_on_fail]=[true]
58407:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(accountid=1)
58408:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [accountid]=[1]
58409:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(account_type=3)
58410:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [account_type]=[3]
58411:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(resellerid=0)
58412:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [resellerid]=[0]
58413:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(accountcode=5163162651)
58414:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [accountcode]=[5163162651]
58415:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(call_direction=outbound)
58416:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [call_direction]=[outbound]
58417:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(calltype=STANDARD)
58418:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [calltype]=[STANDARD]
58419:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 sched_hangup(+3951060 allotted_timeout)
58420:2017-10-09 14:56:56.270744 [DEBUG] switch_scheduler.c:249 Added task 33851 switch_ivr_schedule_hangup (9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a) to run at 1511526476
58421:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 log(INFO [Customer info] : [Account code: 5163162651, Rategroup Id: 1, Called number: 375295498461])
58422:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [INFO] mod_dptools.c:1628 [Customer info] : [Account code: 5163162651, Rategroup Id: 1, Called number: 375295498461]
58423:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(origination_rates=ID:47093|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.36252|INC:0|RATEGROUP:1|MARKUP:20|ACCID:1)
58424:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [origination_rates]=[ID:47093|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.36252|INC:0|RATEGROUP:1|MARKUP:20|ACCID:1]
58425:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 limit(db 5163162651 db_5163162651 1)
58426:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] switch_limit.c:126 incr called: 5163162651_db_5163162651 max:1, interval:0
58427:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.270744 [DEBUG] mod_db.c:193 Usage for 5163162651_db_5163162651 is now 1/1
58428:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(effective_destination_number=375295498461)
58429:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.290751 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [effective_destination_number]=[375295498461]
58430:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 set(termination_rates=ID:1539|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02000|INC:0|TRUNK:2|PROVIDER:1)
58431:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.290751 [DEBUG] mod_dptools.c:1435 sofia/default/6501303426@81.95.119.150 SET [termination_rates]=[ID:1539|CODE:^37529.*|DESTINATION:Belarus - Mobile Others|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.02000|INC:0|TRUNK:2|PROVIDER:1]
58432:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a EXECUTE sofia/default/6501303426@81.95.119.150 bridge(sofia/gateway/Voipro_gris/375295498461)
58433:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:56.290751 [DEBUG] switch_ivr_originate.c:2079 Parsing global variables
58495:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_channel.c:3395 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58497:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [INFO] switch_ivr_originate.c:3501 Sending early media
58498:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_media.c:5111 AUDIO RTP [sofia/default/6501303426@81.95.119.150] 81.95.119.150 port 17102 -> 100.68.87.30 port 9224 codec: 0 ms: 20
58499:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_rtp.c:3530 Not using a timer
58500:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_media.c:5409 Set 2833 dtmf send payload to 101
58501:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_media.c:5415 Set 2833 dtmf receive payload to 101
58502:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] mod_sofia.c:2247 Ring SDP:
58503:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a v=0
58504:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a o=FreeSWITCH 1507558317 1507558318 IN IP4 81.95.119.150
58505:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a s=FreeSWITCH
58506:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a c=IN IP4 81.95.119.150
58507:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a t=0 0
58508:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a m=audio 17102 RTP/AVP 0 101
58509:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:0 PCMU/8000
58510:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:101 telephone-event/8000
58511:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=fmtp:101 0-16
58512:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=ptime:20
58513:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=sendrecv
58514:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58515:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [NOTICE] mod_sofia.c:2250 Pre-Answer sofia/default/6501303426@81.95.119.150!
58516:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_channel.c:3399 (sofia/default/6501303426@81.95.119.150) Callstate Change RINGING -> EARLY
58517:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_session.c:908 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58518:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_ivr_originate.c:3552 Originate Resulted in Success: [sofia/default/375295498461]
58519:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58520:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [early][183]
58522:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:56:59.850729 [DEBUG] switch_core_session.c:908 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58563:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] switch_channel.c:3635 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58566:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] mod_sofia.c:780 Local SDP sofia/default/6501303426@81.95.119.150:
58567:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a v=0
58568:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a o=FreeSWITCH 1507558317 1507558319 IN IP4 81.95.119.150
58569:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a s=FreeSWITCH
58570:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a c=IN IP4 81.95.119.150
58571:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a t=0 0
58572:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a m=audio 17102 RTP/AVP 0 101
58573:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:0 PCMU/8000
58574:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:101 telephone-event/8000
58575:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=fmtp:101 0-16
58576:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=ptime:20
58577:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=sendrecv
58578:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58579:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] switch_core_session.c:908 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58580:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [NOTICE] switch_ivr_bridge.c:496 Channel [sofia/default/6501303426@81.95.119.150] has been answered
58581:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58582:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] switch_channel.c:3689 (sofia/default/6501303426@81.95.119.150) Callstate Change EARLY -> ACTIVE
58583:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.630750 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [completed][200]
58584:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.870751 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58585:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.870751 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58586:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.870751 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58587:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.870751 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [ready][200]
58589:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:57:01.870751 [DEBUG] switch_core_session.c:970 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58595:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.170730 [DEBUG] switch_ivr_bridge.c:470 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58596:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.170730 [DEBUG] switch_rtp.c:4513 Send start packet for [4] ts=1270011577 dur=160/160/800 seq=25467 lw=1270011577
58597:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.170730 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270011577 dur=320/320/800 seq=25468 lw=1270011737
58598:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.190731 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270011577 dur=480/480/800 seq=25469 lw=1270011897
58599:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.190731 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270011577 dur=640/640/800 seq=25470 lw=1270012057
58600:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.210729 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270011577 dur=800/800/800 seq=25471 lw=1270012057
58601:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.210729 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270011577 dur=800/800/800 seq=25472 lw=1270012057
58602:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:58:58.210729 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270011577 dur=800/800/800 seq=25473 lw=1270012057
58639:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 14:59:43.770734 [INFO] switch_rtp.c:5799 Auto Changing port from 100.68.87.30:9224 to 173.195.0.12:62555
58679:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.930733 [DEBUG] switch_ivr_bridge.c:470 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58680:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.930733 [DEBUG] switch_rtp.c:4513 Send start packet for [4] ts=1270857657 dur=160/160/800 seq=30759 lw=1270857657
58681:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.930733 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270857657 dur=320/320/800 seq=30760 lw=1270857817
58682:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.950734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270857657 dur=480/480/800 seq=30761 lw=1270857977
58683:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.950734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1270857657 dur=640/640/800 seq=30762 lw=1270858137
58684:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.970734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270857657 dur=800/800/800 seq=30763 lw=1270858137
58685:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.970734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270857657 dur=800/800/800 seq=30764 lw=1270858137
58686:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:00:43.970734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1270857657 dur=800/800/800 seq=30765 lw=1270858137
58730:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.230733 [DEBUG] switch_ivr_bridge.c:470 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58731:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.230733 [DEBUG] switch_rtp.c:4513 Send start packet for [4] ts=1273395897 dur=160/160/800 seq=46626 lw=1273395897
58732:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.230733 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1273395897 dur=320/320/800 seq=46627 lw=1273396057
58733:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.250734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1273395897 dur=480/480/800 seq=46628 lw=1273396217
58734:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.250734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1273395897 dur=640/640/800 seq=46629 lw=1273396377
58735:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.270734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1273395897 dur=800/800/800 seq=46630 lw=1273396377
58736:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.270734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1273395897 dur=800/800/800 seq=46631 lw=1273396377
58737:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:06:01.270734 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1273395897 dur=800/800/800 seq=46632 lw=1273396377
58742:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:46.990734 [DEBUG] switch_ivr_bridge.c:470 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58743:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:46.990734 [DEBUG] switch_rtp.c:4513 Send start packet for [4] ts=1274241817 dur=160/160/800 seq=51917 lw=1274241817
58744:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:46.990734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1274241817 dur=320/320/800 seq=51918 lw=1274241977
58745:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:47.010733 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1274241817 dur=480/480/800 seq=51919 lw=1274242137
58746:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:47.010733 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1274241817 dur=640/640/800 seq=51920 lw=1274242297
58747:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:47.030731 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1274241817 dur=800/800/800 seq=51921 lw=1274242297
58748:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:47.030731 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1274241817 dur=800/800/800 seq=51922 lw=1274242297
58749:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:07:47.030731 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1274241817 dur=800/800/800 seq=51923 lw=1274242297
58826:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.510734 [DEBUG] switch_ivr_bridge.c:470 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58827:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.510734 [DEBUG] switch_rtp.c:4513 Send start packet for [4] ts=1275934137 dur=160/160/800 seq=62497 lw=1275934137
58828:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.510734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1275934137 dur=320/320/800 seq=62498 lw=1275934297
58829:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.530734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1275934137 dur=480/480/800 seq=62499 lw=1275934457
58830:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.530734 [DEBUG] switch_rtp.c:4412 Send middle packet for [4] ts=1275934137 dur=640/640/800 seq=62500 lw=1275934617
58831:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.550737 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1275934137 dur=800/800/800 seq=62501 lw=1275934617
58832:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.550737 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1275934137 dur=800/800/800 seq=62502 lw=1275934617
58833:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:11:18.550737 [DEBUG] switch_rtp.c:4412 Send end packet for [4] ts=1275934137 dur=800/800/800 seq=62503 lw=1275934617
58874:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:48.950733 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58875:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:48.950733 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58876:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:48.950733 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [received][100]
58877:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:48.950733 [DEBUG] sofia.c:6624 Remote SDP:
58878:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a v=0
58879:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a o=- 968566 2041804 IN IP4 100.68.87.30
58880:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a s=eyeBeam
58881:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a c=IN IP4 0.0.0.0
58882:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a t=0 0
58883:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a m=audio 9224 RTP/AVP 0 101
58884:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=rtpmap:101 telephone-event/8000
58885:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=fmtp:101 0-15
58886:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=sendonly
58887:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a a=alt:1 1 : C8EA855A 0000008B 100.68.87.30 9224
58888:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a
58889:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:48.950733 [DEBUG] switch_channel.c:1787 (sofia/default/6501303426@81.95.119.150) Callstate Change ACTIVE -> HELD
58896:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
58897:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3670 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
58898:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
58899:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
58900:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3531 Set telephone-event payload to 101
58901:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:3861 Set 2833 dtmf send/recv payload to 101
58902:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:5100 Audio params changed for sofia/default/6501303426@81.95.119.150 from 100.68.87.30:9224 to 0.0.0.0:9224
58903:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:5111 AUDIO RTP [sofia/default/6501303426@81.95.119.150] 81.95.119.150 port 17102 -> 0.0.0.0 port 9224 codec: 0 ms: 20
58904:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_media.c:5140 AUDIO RTP CHANGING DEST TO: [0.0.0.0:9224]
58905:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] sofia.c:7259 Processing updated SDP
58906:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58907:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.190734 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [completed][200]
58908:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.630765 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58909:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.630765 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58910:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.630765 [DEBUG] switch_core_session.c:1053 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
58911:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:14:49.630765 [DEBUG] sofia.c:6614 Channel sofia/default/6501303426@81.95.119.150 entering state [ready][200]
59259:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.090733 [DEBUG] switch_channel.c:1961 (sofia/default/6501303426@81.95.119.150) Callstate Change HELD -> UNHELD
59260:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.090733 [NOTICE] switch_core_media.c:1792 Hangup sofia/default/6501303426@81.95.119.150 [CS_EXECUTE] [MEDIA_TIMEOUT]
59261:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.090733 [DEBUG] switch_channel.c:3222 Send signal sofia/default/6501303426@81.95.119.150 [KILL]
59262:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
59263:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [sofia/default/6501303426@81.95.119.150]
59272:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_ivr_bridge.c:395 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
59274:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_ivr_bridge.c:1566 sofia/default/6501303426@81.95.119.150 skip receive message [UNBRIDGE] (channel is hungup already)
59275:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_session.c:2893 sofia/default/6501303426@81.95.119.150 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
59276:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:535 (sofia/default/6501303426@81.95.119.150) State EXECUTE going to sleep
59277:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_HANGUP
59279:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:735 (sofia/default/6501303426@81.95.119.150) Callstate Change UNHELD -> HANGUP
59280:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:737 (sofia/default/6501303426@81.95.119.150) State HANGUP
59281:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] mod_sofia.c:413 Channel sofia/default/6501303426@81.95.119.150 hanging up, cause: MEDIA_TIMEOUT
59282:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/default/6501303426@81.95.119.150
59283:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:60 sofia/default/6501303426@81.95.119.150 Standard HANGUP, cause: MEDIA_TIMEOUT
59284:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:737 (sofia/default/6501303426@81.95.119.150) State HANGUP going to sleep
59285:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:504 (sofia/default/6501303426@81.95.119.150) State Change CS_HANGUP -> CS_REPORTING
59286:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
59287:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:472 (sofia/default/6501303426@81.95.119.150) Running State Change CS_REPORTING
59288:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_core_state_machine.c:823 (sofia/default/6501303426@81.95.119.150) State REPORTING
59290:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.110732 [DEBUG] switch_ivr_bridge.c:690 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
59315:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:104 sofia/default/6501303426@81.95.119.150 Standard REPORTING, cause: MEDIA_TIMEOUT
59316:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:823 (sofia/default/6501303426@81.95.119.150) State REPORTING going to sleep
59317:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:498 (sofia/default/6501303426@81.95.119.150) State Change CS_REPORTING -> CS_DESTROY
59318:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_session.c:1388 Send signal sofia/default/6501303426@81.95.119.150 [BREAK]
59319:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_session.c:1615 Session 143598 (sofia/default/6501303426@81.95.119.150) Locked, Waiting on external entities
59320:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [NOTICE] switch_core_session.c:1633 Session 143598 (sofia/default/6501303426@81.95.119.150) Ended
59321:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [NOTICE] switch_core_session.c:1637 Close Channel sofia/default/6501303426@81.95.119.150 [CS_DESTROY]
59322:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:626 (sofia/default/6501303426@81.95.119.150) Running State Change CS_DESTROY
59323:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:636 (sofia/default/6501303426@81.95.119.150) State DESTROY
59324:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] mod_sofia.c:323 sofia/default/6501303426@81.95.119.150 SOFIA DESTROY
59325:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:111 sofia/default/6501303426@81.95.119.150 Standard DESTROY
59326:9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a 2017-10-09 15:24:50.390734 [DEBUG] switch_core_state_machine.c:636 (sofia/default/6501303426@81.95.119.150) State DESTROY going to sleep
59327:2017-10-09 15:24:50.570740 [DEBUG] switch_scheduler.c:144 Deleting task 33851 switch_ivr_schedule_hangup (9ecd05c0-ad23-11e7-986b-e5cbfaf8d67a)
[root@switch log]#