<html><head><title>Re: [Freeswitch-dev] Strange NAT errors</title>
<META http-equiv=Content-Type content="text/html; charset=koi8-r">
</head>
<body>
<span style=" font-family:'Verdana'; font-size: 10pt;">&nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Hi, Anthony!<br>
<br>
&nbsp; &nbsp; So happened, what Monday, August 24, 2009, at 23:17, Anthony Minessale<br>
wrote cabbalagram to Sergey Popov:<br>
<br>
</span><table>
<tr>
<td width=1 bgcolor= #0000ff><br>
</td>
<td width=911><span style=" font-family:'verdana'; font-size: 10pt;">that looks like a bug to me in the code where uninit memory is confused for an ip change<br>
please try r14623</td>
</tr>
</table>
<span style=" font-family:'Verdana'; font-size: 10pt;"><br><br>
&nbsp; &nbsp; Really fixed. Thanks!<br>
<br>
</span><table>
<tr>
<td width=1 bgcolor= #0000ff><br>
</td>
<td width=911><span style=" font-family:'Verdana'; font-size: 10pt;"><br><br>
<br>
2009/8/24 Sergey Popov &lt;<a href="mailto:prof@ukrpost.net">prof@ukrpost.net</a>&gt;<br>
&nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Hi, All!<br>
<br>
&nbsp; &nbsp; Last time FS on random time write NAT errors to console. It can repeat oneself many times and stop randomly. After that occurrence skypiax stop working. It occur on today version FS from SVN, and binary installation 1.0.4. Version from March is free from this error.<br>
<br>
OS: Vista<br>
Configuration: default + skypiax + mod_managed<br>
IP address 192.168.0.2, NAT on router (D-Link DI-524)<br>
<br>
Value of field network-address-change-v4 every time consist meaningless set of random symbols. And my IP addresses is not changed really. What can it be?<br>
<br>
2009-08-24 18:20:50.910823 [INFO] switch_nat.c:305 Public IP changed from '77.122.60.138' to 'ννννννννννννννννννννh'.<br>
2009-08-24 18:20:50.910823 [INFO] switch_nat.c:392 Scanning for NAT<br>
2009-08-24 18:20:50.910823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change detected<br>
2009-08-24 18:20:50.910823 [INFO] mod_sofia.c:3288 IP change detected [77.122.60.138]-&gt;[ννννννννννννννννννννh] []-&gt;[]<br>
2009-08-24 18:20:50.910823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5<br>
2009-08-24 18:20:50.912823 [ERR] switch_nat.c:183 Error checking for PMP [general error]<br>
2009-08-24 18:20:50.912823 [DEBUG] switch_nat.c:397 Checking for UPnP<br>
2009-08-24 18:20:51.775823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]<br>
2009-08-24 18:20:51.775823 [INFO] mod_enum.c:808 ENUM Reloaded<br>
2009-08-24 18:20:51.775823 [INFO] switch_time.c:661 Timezone reloaded 530 definitions<br>
2009-08-24 18:20:52.483823 [DEBUG] sofia.c:983 Write lock internal-ipv6<br>
2009-08-24 18:20:52.497823 [NOTICE] sofia.c:990 Waiting for worker thread<br>
2009-08-24 18:20:52.498823 [DEBUG] sofia.c:1048 Write unlock internal-ipv6<br>
2009-08-24 18:20:52.499823 [NOTICE] sofia.c:2850 Started Profile internal-ipv6 [sofia_reg_internal-ipv6]<br>
2009-08-24 18:20:52.500823 [DEBUG] sofia.c:821 Creating agent for internal-ipv6<br>
2009-08-24 18:20:52.508823 [DEBUG] sofia.c:983 Write lock internal<br>
2009-08-24 18:20:52.509823 [DEBUG] sofia.c:983 Write lock external<br>
2009-08-24 18:20:52.523823 [NOTICE] sofia.c:990 Waiting for worker thread<br>
2009-08-24 18:20:52.527823 [DEBUG] sofia.c:1048 Write unlock internal<br>
2009-08-24 18:20:52.527823 [NOTICE] sofia.c:1517 Adding Alias [192.168.0.2] for profile [internal]<br>
2009-08-24 18:20:52.527823 [NOTICE] sofia.c:2850 Started Profile internal [sofia_reg_internal]<br>
2009-08-24 18:20:52.528823 [DEBUG] sofia.c:821 Creating agent for internal<br>
2009-08-24 18:20:52.541823 [NOTICE] sofia.c:990 Waiting for worker thread<br>
2009-08-24 18:20:52.545823 [NOTICE] sofia_glue.c:3567 deleted gateway&nbsp;<a href="http://example.com">example.com</a><br>
2009-08-24 18:20:52.545823 [DEBUG] sofia.c:1048 Write unlock external<br>
2009-08-24 18:20:52.545823 [NOTICE] sofia_reg.c:2080 Added gateway '<a href="http://example.com">example.com</a>' to profile 'external'<br>
2009-08-24 18:20:52.545823 [NOTICE] sofia.c:2850 Started Profile external [sofia_reg_external]<br>
2009-08-24 18:20:52.545823 [DEBUG] sofia.c:821 Creating agent for external<br>
2009-08-24 18:20:52.607823 [DEBUG] sofia.c:876 Created agent for internal-ipv6<br>
2009-08-24 18:20:52.607823 [DEBUG] sofia.c:909 Set params for internal-ipv6<br>
2009-08-24 18:20:52.607823 [DEBUG] sofia.c:929 Activated db for internal-ipv6<br>
2009-08-24 18:20:52.611823 [DEBUG] sofia.c:956 Starting thread for internal-ipv6<br>
2009-08-24 18:20:52.637823 [DEBUG] sofia.c:876 Created agent for external<br>
2009-08-24 18:20:52.637823 [DEBUG] sofia.c:909 Set params for external<br>
2009-08-24 18:20:52.637823 [DEBUG] sofia.c:929 Activated db for external<br>
2009-08-24 18:20:52.637823 [DEBUG] sofia.c:956 Starting thread for external<br>
2009-08-24 18:20:52.646823 [DEBUG] sofia.c:876 Created agent for internal<br>
2009-08-24 18:20:52.646823 [DEBUG] sofia.c:909 Set params for internal<br>
2009-08-24 18:20:52.646823 [DEBUG] sofia.c:929 Activated db for internal<br>
2009-08-24 18:20:52.657823 [DEBUG] sofia.c:956 Starting thread for internal<br>
2009-08-24 18:20:53.941823 [INFO] switch_nat.c:405 NAT detected type: upnp, ExtIP: '77.122.60.138'<br>
2009-08-24 18:20:53.941823 [DEBUG] switch_nat.c:623 Refreshing nat maps<br>
2009-08-24 18:20:53.965823 [DEBUG] switch_nat.c:482 mapped public port 5060 protocol UDP to localport 5060<br>
2009-08-24 18:20:53.980823 [DEBUG] switch_nat.c:482 mapped public port 5060 protocol TCP to localport 5060<br>
2009-08-24 18:20:53.995823 [DEBUG] switch_nat.c:482 mapped public port 5080 protocol UDP to localport 5080<br>
2009-08-24 18:20:54.10823 [DEBUG] switch_nat.c:482 mapped public port 5080 protocol TCP to localport 5080<br>
2009-08-24 18:20:54.10823 [INFO] switch_nat.c:305 Public IP changed from '77.122.60.138' to 'ννννννννννννννννννννh'.<br>
2009-08-24 18:20:54.10823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change detected<br>
2009-08-24 18:20:54.10823 [INFO] mod_sofia.c:3288 IP change detected [77.122.60.138]-&gt;[ννννννννννννννννννννh] []-&gt;[]<br>
2009-08-24 18:20:54.10823 [INFO] switch_nat.c:392 Scanning for NAT<br>
2009-08-24 18:20:54.10823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5<br>
2009-08-24 18:20:54.13823 [ERR] switch_nat.c:183 Error checking for PMP [general error]<br>
2009-08-24 18:20:54.13823 [DEBUG] switch_nat.c:397 Checking for UPnP<br>
2009-08-24 18:20:54.847823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]<br>
2009-08-24 18:20:54.847823 [ERR] sofia_glue.c:3522 Profile internal must be up for at least 10 seconds to stop/restart.<br>
Please wait 8 seconds<br>
2009-08-24 18:20:54.847823 [ERR] sofia_glue.c:3522 Profile external must be up for at least 10 seconds to stop/restart.<br>
Please wait 8 seconds<br>
2009-08-24 18:20:54.847823 [ERR] sofia_glue.c:3522 Profile internal-ipv6 must be up for at least 10 seconds to stop/restart.<br>
Please wait 8 seconds<br>
2009-08-24 18:20:54.847823 [ERR] sofia_glue.c:3522 Profile internal must be up for at least 10 seconds to stop/restart.<br>
Please wait 8 seconds<br>
2009-08-24 18:20:54.847823 [INFO] mod_enum.c:808 ENUM Reloaded<br>
2009-08-24 18:20:54.848823 [INFO] switch_time.c:661 Timezone reloaded 530 definitions<br>
2009-08-24 18:20:57.40823 [INFO] switch_nat.c:405 NAT detected type: upnp, ExtIP: '77.122.60.138'<br>
2009-08-24 18:20:57.40823 [DEBUG] switch_nat.c:623 Refreshing nat maps<br>
2009-08-24 18:20:57.61823 [DEBUG] switch_nat.c:482 mapped public port 5060 protocol UDP to localport 5060<br>
2009-08-24 18:20:57.75823 [DEBUG] switch_nat.c:482 mapped public port 5060 protocol TCP to localport 5060<br>
2009-08-24 18:20:57.88823 [DEBUG] switch_nat.c:482 mapped public port 5080 protocol UDP to localport 5080<br>
2009-08-24 18:20:57.102823 [DEBUG] switch_nat.c:482 mapped public port 5080 protocol TCP to localport 5080<br>
2009-08-24 18:20:57.102823 [INFO] switch_nat.c:305 Public IP changed from '77.122.60.138' to 'ννννννννννννννννννννh'.<br>
2009-08-24 18:20:57.102823 [INFO] switch_nat.c:392 Scanning for NAT<br>
2009-08-24 18:20:57.102823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5<br>
2009-08-24 18:20:57.103823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change detected<br>
2009-08-24 18:20:57.103823 [INFO] mod_sofia.c:3288 IP change detected [77.122.60.138]-&gt;[ννννννννννννννννννννh] []-&gt;[]<br>
2009-08-24 18:20:57.103823 [ERR] switch_nat.c:183 Error checking for PMP [general error]<br>
2009-08-24 18:20:57.103823 [DEBUG] switch_nat.c:397 Checking for UPnP<br>
2009-08-24 18:20:57.992823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]<br>
2009-08-24 18:20:57.992823 [ERR] sofia_glue.c:3522 Profile internal must be up for at least 10 seconds to stop/restart.<br>
Please wait 5 seconds<br>
2009-08-24 18:20:57.992823 [ERR] sofia_glue.c:3522 Profile external must be up for at least 10 seconds to stop/restart.<br>
Please wait 5 seconds<br>
2009-08-24 18:20:57.993823 [ERR] sofia_glue.c:3522 Profile internal-ipv6 must be up for at least 10 seconds to stop/restart.<br>
Please wait 5 seconds<br>
2009-08-24 18:20:57.993823 [ERR] sofia_glue.c:3522 Profile internal must be up for at least 10 seconds to stop/restart.<br>
Please wait 5 seconds<br>
2009-08-24 18:20:57.993823 [INFO] mod_enum.c:808 ENUM Reloaded<br>
2009-08-24 18:20:57.993823 [INFO] switch_time.c:661 Timezone reloaded 530 definitions<br>
<br>
Event-Name: TRAP<br>
Core-UUID: f1764d44-a470-834c-9a07-c0225a65232d<br>
FreeSWITCH-Hostname: ProfNest<br>
FreeSWITCH-IPv4: 192.168.0.2<br>
FreeSWITCH-IPv6: %3A%3A1<br>
Event-Date-Local: 2009-08-24%2018%3A22%3A54<br>
Event-Date-GMT: Mon,%2024%20Aug%202009%2015%3A22%3A54%20GMT<br>
Event-Date-Timestamp: 1251127374637823<br>
Event-Calling-File: switch_nat.c<br>
Event-Calling-Function: switch_nat_multicast_runtime<br>
Event-Calling-Line-Number: 308<br>
condition: network-address-change<br>
network-address-previous-v4: 77.122.60.138<br>
network-address-change-v4: %?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?Ch<br>
<br>
<br>
&nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Bye.<br>
<br>
Monday, August 24, 2009, 18:40 &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Sergey Popov.<br>
---<br>
&nbsp;* E-mail&nbsp;<a href="mailto:prof@ukrpost.net">prof@ukrpost.net</a>&nbsp;* ICQ 2831794 *<br>
<br>
_______________________________________________<br>
FreeSWITCH-dev mailing list<br>
<a href="mailto:FreeSWITCH-dev@lists.freeswitch.org">FreeSWITCH-dev@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev">http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-dev">http://lists.freeswitch.org/mailman/options/freeswitch-dev</a><br>
<a href="http://www.freeswitch.org">http://www.freeswitch.org</a><br>
<br>
<br>
<br>
<br>
--&nbsp;<br>
Anthony Minessale II<br>
<br>
FreeSWITCH&nbsp;<a href="http://www.freeswitch.org/">http://www.freeswitch.org/</a><br>
ClueCon&nbsp;<a href="http://www.cluecon.com/">http://www.cluecon.com/</a><br>
Twitter:&nbsp;<a href="http://twitter.com/FreeSWITCH_wire">http://twitter.com/FreeSWITCH_wire</a><br>
<br>
AIM: anthm<br>
<a href="mailto:MSN%3Aanthony_minessale@hotmail.com">MSN:anthony_minessale@hotmail.com</a><br>
GTALK/JABBER/<a href="mailto:PAYPAL%3Aanthony.minessale@gmail.com">PAYPAL:anthony.minessale@gmail.com</a><br>
IRC:&nbsp;<a href="http://irc.freenode.net">irc.freenode.net</a>&nbsp;#freeswitch<br>
<br>
FreeSWITCH Developer Conference<br>
<a href="mailto:sip%3A888@conference.freeswitch.org">sip:888@conference.freeswitch.org</a><br>
<a href="http://iax:guest@conference.freeswitch.org/888">iax:guest@conference.freeswitch.org/888</a><br>
<a href="mailto:googletalk%3Aconf%2B888@conference.freeswitch.org">googletalk:conf+888@conference.freeswitch.org</a><br>
pstn:213-799-1400</td>
</tr>
</table>
<span style=" font-family:'Verdana'; font-size: 10pt;"><br><br>
&nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Bye.<br>
<br>
Tuesday, August 25, 2009, 15:43 &nbsp; &nbsp; &nbsp; &nbsp; &nbsp; &nbsp;Sergey Popov.<br>
---<br>
&nbsp;* E-mail&nbsp;<a href="mailto:prof@ukrpost.net">prof@ukrpost.net</a>&nbsp;* ICQ 2831794 *</body>