2018-03-15 19:55:50.438535 [DEBUG]   switch_ivr.c:2160 (sofia/external/testcall@127.0.0.1) State Change CS_EXECUTE -> CS_ROUTING
2018-03-15 19:55:50.438535 [NOTICE]  switch_ivr.c:2167 Transfer sofia/external/testcall@127.0.0.1 to XML[transfer_extension@second_extension]
2018-03-15 19:55:50.438535 [DEBUG]   switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/myfiles/942.wav
2018-03-15 19:55:50.438535 [WARNING] switch_ivr_play_say.c:2636 PAGD failure! Transfer to: vfile_failure / XML / first_extension
2018-03-15 19:55:50.438535 [NOTICE]  switch_ivr.c:2167 Transfer sofia/external/testcall@127.0.0.1 to XML[vfile_failure@first_extension]
2018-03-15 19:55:50.438535 [DEBUG]   switch_core_state_machine.c:650 (sofia/external/testcall@127.0.0.1) State EXECUTE going to sleep
2018-03-15 19:55:50.438535 [DEBUG]   switch_core_state_machine.c:584 (sofia/external/testcall@127.0.0.1) Running State Change CS_ROUTING (Cur 1 Tot 27)
2018-03-15 19:55:50.438535 [DEBUG]   switch_channel.c:2249 (sofia/external/testcall@127.0.0.1) Callstate Change ACTIVE -> RINGING
2018-03-15 19:55:50.438535 [DEBUG]   switch_core_state_machine.c:643 (sofia/external/testcall@127.0.0.1) State ROUTING
2018-03-15 19:55:50.438535 [DEBUG]   mod_sofia.c:143 sofia/external/testcall@127.0.0.1 SOFIA ROUTING
2018-03-15 19:55:50.438535 [DEBUG]   switch_core_state_machine.c:236 sofia/external/testcall@127.0.0.1 Standard ROUTING
2018-03-15 19:55:50.438535 [INFO]    mod_dialplan_xml.c:637 Processing FancyCID <testcall>->vfile_failure in context first_extension