EXECUTE sofia/internal/
1001@working.server.edu hash(insert/working.server.edu-spymap/1001/e8b73a98-b783-4c73-8547-2e2c20786b0d)
EXECUTE sofia/internal/
1001@working.server.edu hash(insert/working.server.edu-last_dial/global/e8b73a98-b783-4c73-8547-2e2c20786b0d)
2010-03-30 21:20:51.763507 [DEBUG] switch_cpp.cpp:554 sofia/internal/
1001@working.server.edu receive message [ANSWER]
2010-03-30 21:20:51.763507 [DEBUG] switch_rtp.c:1167 Starting timer [soft] 160 bytes per 20ms
v=0
s=FreeSWITCH
t=0 0
m=audio 32338 RTP/AVP 3 101
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2010-03-30 21:20:51.765633 [DEBUG] switch_core_session.c:645 Send signal sofia/internal/
1001@working.server.edu [BREAK]
2010-03-30 21:20:51.765633 [DEBUG] sofia.c:3774 Channel sofia/internal/
1001@working.server.edu entering state [completed][200]
2010-03-30 21:20:51.765633 [NOTICE] switch_cpp.cpp:554 Channel [sofia/internal/
1001@working.server.edu] has been answered
2010-03-30 21:20:51.765633 [DEBUG] switch_channel.c:182 sofia/internal/
1001@working.server.edu receive message [AUDIO_SYNC]
2010-03-30 21:20:52.132157 [DEBUG] sofia.c:3774 Channel sofia/internal/
1001@working.server.edu entering state [ready][200]
2010-03-30 21:20:52.766898 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:20:52.766898 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:11.985960 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:12.486077 [INFO] switch_cpp.cpp:1129 otalo.lua : SELECT id, name_file FROM app_tbl ORDER BY id ASC
2010-03-30 21:21:12.486077 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:12.486077 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:12.506154 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:12.506154 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:14.006120 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:14.026376 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:14.026376 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:17.146408 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:17.166614 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:17.166614 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:17.866526 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:18.366651 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:18.366651 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:18.386040 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:18.386040 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:20.146593 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:20.166701 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:20.166701 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:23.286448 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:23.306606 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:23.306606 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:24.126360 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:24.646291 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:24.646291 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:24.666736 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:24.666736 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:26.166139 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:26.186290 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:26.186290 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:29.306221 [DEBUG] switch_ivr_play_say.c:1446 done playing file
2010-03-30 21:21:29.326378 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms
2010-03-30 21:21:29.326378 [DEBUG] switch_core_io.c:652 sofia/internal/
1001@working.server.edu receive message [TRANSCODING_NECESSARY]
2010-03-30 21:21:30.126585 [DEBUG] switch_ivr_play_say.c:1446 done playing file
I think the most significantly the broken server doesn't give the '... receive message [TRANSCODING_NECESSARY]' output. The new server is Ubuntu 9.0.4 running FS downloaded from main trunk in Dec 09, the broken is CentOS 5.4 running FS main trunk downloaded this month (not sure how to get version info from FS).