Here is the log output (with names/IPs hidden) from my broken server executing my lua app:<div><br></div><div><div><div><div>EXECUTE sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> hash(insert/broken.server.IP-spymap/1001/e93b00d7-fb0f-413b-9f17-207ea444239a)</div>
<div>EXECUTE sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> hash(insert/broken.server.IP-last_dial/1001/5000)</div><div>EXECUTE sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> hash(insert/broken.server.IP-last_dial/global/e93b00d7-fb0f-413b-9f17-207ea444239a)</div>
<div>EXECUTE sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> lua(AO/otalo.lua)</div><div>2010-03-30 08:49:24.092796 [INFO] switch_cpp.cpp:1142 otalo.lua : caller id = 1001</div><div>2010-03-30 08:49:24.093798 [INFO] switch_cpp.cpp:1142 otalo.lua : user id = 1</div>
<div>2010-03-30 08:49:24.099786 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a>] broken.server.IP port 26884 -> 127.0.0.1 port 4000 codec: 3 ms: 20</div>
<div>2010-03-30 08:49:24.099786 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms</div><div>2010-03-30 08:49:24.101833 [DEBUG] mod_sofia.c:636 Local SDP sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a>:</div>
<div>v=0</div><div>o=FreeSWITCH 1269937280 1269937281 IN IP4 broken.server.IP</div><div>s=FreeSWITCH</div><div>c=IN IP4 broken.server.IP</div><div>t=0 0</div><div>m=audio 26884 RTP/AVP 3 101</div><div>a=rtpmap:3 GSM/8000</div>
<div>a=rtpmap:101 telephone-event/8000</div><div>a=fmtp:101 0-16</div><div>a=silenceSupp:off - - - -</div><div>a=ptime:20</div><div>a=sendrecv</div><div><br></div><div>2010-03-30 08:49:24.101833 [DEBUG] switch_core_session.c:638 Send signal sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> [BREAK]</div>
<div>2010-03-30 08:49:24.101833 [NOTICE] switch_cpp.cpp:554 Channel [sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a>] has been answered</div><div>2010-03-30 08:49:24.101833 [DEBUG] sofia.c:4152 Channel sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> entering state [completed][200]</div>
<div>2010-03-30 08:49:24.146888 [DEBUG] sofia.c:4152 Channel sofia/internal/<a href="mailto:1001@broken.server.edu">1001@broken.server.edu</a> entering state [ready][200]</div><div>2010-03-30 08:49:25.115874 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div>
<div>2010-03-30 08:49:44.335874 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div><div>2010-03-30 08:49:44.835892 [INFO] switch_cpp.cpp:1142 otalo.lua : SELECT id, name_file FROM app_tbl ORDER BY id ASC</div><div>
2010-03-30 08:49:44.835892 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 08:49:44.835892 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div><div>2010-03-30 08:49:44.855887 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div>
<div>2010-03-30 08:49:46.355889 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div><div>2010-03-30 08:49:46.375877 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 08:49:49.495889 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div>
<div>2010-03-30 08:49:49.515889 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 08:49:50.215892 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div><div>2010-03-30 08:49:50.735890 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div>
<div>2010-03-30 08:49:50.735890 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div><div>2010-03-30 08:49:50.755890 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 08:49:52.515889 [DEBUG] switch_ivr_play_say.c:1444 done playing file</div>
<div>2010-03-30 08:49:52.535889 [DEBUG] switch_ivr_play_say.c:1152 Codec Activated L16@8000hz 1 channels 20ms</div></div></div><div><br></div><div>And here is pretty much up to the same point in the app executed on my older, working server:</div>
<div><br></div><div><div><div>EXECUTE sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> hash(insert/working.server.edu-spymap/1001/e8b73a98-b783-4c73-8547-2e2c20786b0d)</div><div>EXECUTE sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> hash(insert/working.server.edu-last_dial/1001/5000)</div>
<div>EXECUTE sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> hash(insert/working.server.edu-last_dial/global/e8b73a98-b783-4c73-8547-2e2c20786b0d)</div><div>EXECUTE sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> lua(AO/otalo.lua)</div>
<div>2010-03-30 21:20:51.763507 [DEBUG] switch_cpp.cpp:554 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [ANSWER]</div><div>2010-03-30 21:20:51.763507 [DEBUG] sofia_glue.c:2381 AUDIO RTP [sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a>] <a href="http://working.server.edu">working.server.edu</a> port 32338 -> 127.0.0.1 port 4002 codec: 3 ms: 20</div>
<div>2010-03-30 21:20:51.763507 [DEBUG] switch_rtp.c:1167 Starting timer [soft] 160 bytes per 20ms</div><div>2010-03-30 21:20:51.764569 [DEBUG] mod_sofia.c:571 Local SDP sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a>:</div>
<div>v=0</div><div>o=FreeSWITCH 1269931913 1269931914 IN IP4 <a href="http://working.server.edu">working.server.edu</a></div><div>s=FreeSWITCH</div><div>c=IN IP4 <a href="http://working.server.edu">working.server.edu</a></div>
<div>t=0 0</div><div>m=audio 32338 RTP/AVP 3 101</div><div>a=rtpmap:3 GSM/8000</div><div>a=rtpmap:101 telephone-event/8000</div><div>a=fmtp:101 0-16</div><div>a=silenceSupp:off - - - -</div><div>a=ptime:20</div><div>a=sendrecv</div>
<div><br></div><div>2010-03-30 21:20:51.765633 [DEBUG] switch_core_session.c:645 Send signal sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> [BREAK]</div><div>2010-03-30 21:20:51.765633 [DEBUG] sofia.c:3774 Channel sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> entering state [completed][200]</div>
<div>2010-03-30 21:20:51.765633 [NOTICE] switch_cpp.cpp:554 Channel [sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a>] has been answered</div><div>2010-03-30 21:20:51.765633 [DEBUG] switch_channel.c:182 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [AUDIO_SYNC]</div>
<div>2010-03-30 21:20:52.132157 [DEBUG] sofia.c:3774 Channel sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> entering state [ready][200]</div><div>2010-03-30 21:20:52.346042 [INFO] switch_rtp.c:1987 Auto Changing port from <a href="http://127.0.0.1:4002">127.0.0.1:4002</a> to <a href="http://75.42.72.134:4002">75.42.72.134:4002</a></div>
<div>2010-03-30 21:20:52.766898 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:20:52.766898 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:11.985960 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>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</div><div>
2010-03-30 21:21:12.486077 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:12.486077 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:12.506154 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div>
<div>2010-03-30 21:21:12.506154 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div><div>2010-03-30 21:21:14.006120 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div>
<div>2010-03-30 21:21:14.026376 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:14.026376 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:17.146408 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:17.166614 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:17.166614 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:17.866526 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:18.366651 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:18.366651 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div>
<div>2010-03-30 21:21:18.386040 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:18.386040 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:20.146593 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:20.166701 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:20.166701 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:23.286448 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:23.306606 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:23.306606 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:24.126360 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:24.646291 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:24.646291 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div>
<div>2010-03-30 21:21:24.666736 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:24.666736 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:26.166139 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:26.186290 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:26.186290 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:29.306221 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div>2010-03-30 21:21:29.326378 [DEBUG] switch_ivr_play_say.c:1154 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-03-30 21:21:29.326378 [DEBUG] switch_core_io.c:652 sofia/internal/<a href="mailto:1001@working.server.edu">1001@working.server.edu</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2010-03-30 21:21:30.126585 [DEBUG] switch_ivr_play_say.c:1446 done playing file</div><div><br></div></div><div><br></div><div>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).</div>
<div><br></div><div>Let me know if more info is necessary to solve this, and thanks again!</div><div>Neil</div></div><br><div class="gmail_quote">On Mon, Mar 29, 2010 at 9:19 PM, Neil Patel <span dir="ltr"><<a href="mailto:neilp@cs.stanford.edu">neilp@cs.stanford.edu</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex;"><div>oops, sorry about that. Here's read:</div><div><br></div><div><div>function read(file, delay)</div><div> if (digits == "") then</div>
<div> logfile:write(sessid, "\t",</div><div><span style="white-space:pre"> </span> session:getVariable("caller_id_number"), "\t",</div>
<div><span style="white-space:pre"> </span> os.time(), "\t", "Prompt", "\t", file, "\n");</div><div> digits = session:read(1, 1, file, delay, "#");</div>
<div> if (digits ~= "") then</div><div><span style="white-space:pre"> </span> logfile:write(sessid, "\t", session:getVariable("caller_id_number"), "\t", os.time(), "\t", "dtmf", "\t", file, "\t", digits, "\n"); </div>
<div> end</div><div> end</div><div>end</div><div><div></div><div class="h5"><div><br></div><div class="gmail_quote">On Mon, Mar 29, 2010 at 8:28 PM, Brian West <span dir="ltr"><<a href="mailto:brian@freeswitch.org" target="_blank">brian@freeswitch.org</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">what is this code?<br>
<br>
/b<br>
<div><br>
On Mar 29, 2010, at 10:21 PM, Neil Patel wrote:<br>
<br>
> read(absolute_path_to_wav, 0);<br>
<br>
<br>
</div>_______________________________________________<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org" target="_blank">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
</blockquote></div><br></div></div></div>
</blockquote></div><br></div>