[Freeswitch-dev] Strange NAT errors
Anthony Minessale
anthony.minessale at gmail.com
Mon Aug 24 13:17:00 PDT 2009
that looks like a bug to me in the code where uninit memory is confused for
an ip change
please try r14623
2009/8/24 Sergey Popov <prof at ukrpost.net>
> Hi, All!
>
> 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.
>
> OS: Vista
> Configuration: default + skypiax + mod_managed
> IP address 192.168.0.2, NAT on router (D-Link DI-524)
>
> 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?
>
> 2009-08-24 18:20:50.910823 [INFO] switch_nat.c:305 Public IP changed from
> '77.122.60.138' to 'ММММММММММММММММММММh'.
> 2009-08-24 18:20:50.910823 [INFO] switch_nat.c:392 Scanning for NAT
> 2009-08-24 18:20:50.910823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change
> detected
> 2009-08-24 18:20:50.910823 [INFO] mod_sofia.c:3288 IP change detected
> [77.122.60.138]->[ММММММММММММММММММММh] []->[]
> 2009-08-24 18:20:50.910823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5
> 2009-08-24 18:20:50.912823 [ERR] switch_nat.c:183 Error checking for PMP
> [general error]
> 2009-08-24 18:20:50.912823 [DEBUG] switch_nat.c:397 Checking for UPnP
> 2009-08-24 18:20:51.775823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]
> 2009-08-24 18:20:51.775823 [INFO] mod_enum.c:808 ENUM Reloaded
> 2009-08-24 18:20:51.775823 [INFO] switch_time.c:661 Timezone reloaded 530
> definitions
> 2009-08-24 18:20:52.483823 [DEBUG] sofia.c:983 Write lock internal-ipv6
> 2009-08-24 18:20:52.497823 [NOTICE] sofia.c:990 Waiting for worker thread
> 2009-08-24 18:20:52.498823 [DEBUG] sofia.c:1048 Write unlock internal-ipv6
> 2009-08-24 18:20:52.499823 [NOTICE] sofia.c:2850 Started Profile
> internal-ipv6 [sofia_reg_internal-ipv6]
> 2009-08-24 18:20:52.500823 [DEBUG] sofia.c:821 Creating agent for
> internal-ipv6
> 2009-08-24 18:20:52.508823 [DEBUG] sofia.c:983 Write lock internal
> 2009-08-24 18:20:52.509823 [DEBUG] sofia.c:983 Write lock external
> 2009-08-24 18:20:52.523823 [NOTICE] sofia.c:990 Waiting for worker thread
> 2009-08-24 18:20:52.527823 [DEBUG] sofia.c:1048 Write unlock internal
> 2009-08-24 18:20:52.527823 [NOTICE] sofia.c:1517 Adding Alias [192.168.0.2]
> for profile [internal]
> 2009-08-24 18:20:52.527823 [NOTICE] sofia.c:2850 Started Profile internal
> [sofia_reg_internal]
> 2009-08-24 18:20:52.528823 [DEBUG] sofia.c:821 Creating agent for internal
> 2009-08-24 18:20:52.541823 [NOTICE] sofia.c:990 Waiting for worker thread
> 2009-08-24 18:20:52.545823 [NOTICE] sofia_glue.c:3567 deleted gateway
> example.com
> 2009-08-24 18:20:52.545823 [DEBUG] sofia.c:1048 Write unlock external
> 2009-08-24 18:20:52.545823 [NOTICE] sofia_reg.c:2080 Added gateway '
> example.com' to profile 'external'
> 2009-08-24 18:20:52.545823 [NOTICE] sofia.c:2850 Started Profile external
> [sofia_reg_external]
> 2009-08-24 18:20:52.545823 [DEBUG] sofia.c:821 Creating agent for external
> 2009-08-24 18:20:52.607823 [DEBUG] sofia.c:876 Created agent for
> internal-ipv6
> 2009-08-24 18:20:52.607823 [DEBUG] sofia.c:909 Set params for internal-ipv6
> 2009-08-24 18:20:52.607823 [DEBUG] sofia.c:929 Activated db for
> internal-ipv6
> 2009-08-24 18:20:52.611823 [DEBUG] sofia.c:956 Starting thread for
> internal-ipv6
> 2009-08-24 18:20:52.637823 [DEBUG] sofia.c:876 Created agent for external
> 2009-08-24 18:20:52.637823 [DEBUG] sofia.c:909 Set params for external
> 2009-08-24 18:20:52.637823 [DEBUG] sofia.c:929 Activated db for external
> 2009-08-24 18:20:52.637823 [DEBUG] sofia.c:956 Starting thread for external
> 2009-08-24 18:20:52.646823 [DEBUG] sofia.c:876 Created agent for internal
> 2009-08-24 18:20:52.646823 [DEBUG] sofia.c:909 Set params for internal
> 2009-08-24 18:20:52.646823 [DEBUG] sofia.c:929 Activated db for internal
> 2009-08-24 18:20:52.657823 [DEBUG] sofia.c:956 Starting thread for internal
> 2009-08-24 18:20:53.941823 [INFO] switch_nat.c:405 NAT detected type: upnp,
> ExtIP: '77.122.60.138'
> 2009-08-24 18:20:53.941823 [DEBUG] switch_nat.c:623 Refreshing nat maps
> 2009-08-24 18:20:53.965823 [DEBUG] switch_nat.c:482 mapped public port 5060
> protocol UDP to localport 5060
> 2009-08-24 18:20:53.980823 [DEBUG] switch_nat.c:482 mapped public port 5060
> protocol TCP to localport 5060
> 2009-08-24 18:20:53.995823 [DEBUG] switch_nat.c:482 mapped public port 5080
> protocol UDP to localport 5080
> 2009-08-24 18:20:54.10823 [DEBUG] switch_nat.c:482 mapped public port 5080
> protocol TCP to localport 5080
> 2009-08-24 18:20:54.10823 [INFO] switch_nat.c:305 Public IP changed from
> '77.122.60.138' to 'ММММММММММММММММММММh'.
> 2009-08-24 18:20:54.10823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change
> detected
> 2009-08-24 18:20:54.10823 [INFO] mod_sofia.c:3288 IP change detected
> [77.122.60.138]->[ММММММММММММММММММММh] []->[]
> 2009-08-24 18:20:54.10823 [INFO] switch_nat.c:392 Scanning for NAT
> 2009-08-24 18:20:54.10823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5
> 2009-08-24 18:20:54.13823 [ERR] switch_nat.c:183 Error checking for PMP
> [general error]
> 2009-08-24 18:20:54.13823 [DEBUG] switch_nat.c:397 Checking for UPnP
> 2009-08-24 18:20:54.847823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]
> 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.
> Please wait 8 seconds
> 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.
> Please wait 8 seconds
> 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.
> Please wait 8 seconds
> 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.
> Please wait 8 seconds
> 2009-08-24 18:20:54.847823 [INFO] mod_enum.c:808 ENUM Reloaded
> 2009-08-24 18:20:54.848823 [INFO] switch_time.c:661 Timezone reloaded 530
> definitions
> 2009-08-24 18:20:57.40823 [INFO] switch_nat.c:405 NAT detected type: upnp,
> ExtIP: '77.122.60.138'
> 2009-08-24 18:20:57.40823 [DEBUG] switch_nat.c:623 Refreshing nat maps
> 2009-08-24 18:20:57.61823 [DEBUG] switch_nat.c:482 mapped public port 5060
> protocol UDP to localport 5060
> 2009-08-24 18:20:57.75823 [DEBUG] switch_nat.c:482 mapped public port 5060
> protocol TCP to localport 5060
> 2009-08-24 18:20:57.88823 [DEBUG] switch_nat.c:482 mapped public port 5080
> protocol UDP to localport 5080
> 2009-08-24 18:20:57.102823 [DEBUG] switch_nat.c:482 mapped public port 5080
> protocol TCP to localport 5080
> 2009-08-24 18:20:57.102823 [INFO] switch_nat.c:305 Public IP changed from
> '77.122.60.138' to 'ММММММММММММММММММММh'.
> 2009-08-24 18:20:57.102823 [INFO] switch_nat.c:392 Scanning for NAT
> 2009-08-24 18:20:57.102823 [DEBUG] switch_nat.c:152 Checking for PMP 1/5
> 2009-08-24 18:20:57.103823 [INFO] mod_sofia.c:3276 EVENT_TRAP: IP change
> detected
> 2009-08-24 18:20:57.103823 [INFO] mod_sofia.c:3288 IP change detected
> [77.122.60.138]->[ММММММММММММММММММММh] []->[]
> 2009-08-24 18:20:57.103823 [ERR] switch_nat.c:183 Error checking for PMP
> [general error]
> 2009-08-24 18:20:57.103823 [DEBUG] switch_nat.c:397 Checking for UPnP
> 2009-08-24 18:20:57.992823 [NOTICE] sofia_glue.c:3506 Reload XML [Success]
> 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.
> Please wait 5 seconds
> 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.
> Please wait 5 seconds
> 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.
> Please wait 5 seconds
> 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.
> Please wait 5 seconds
> 2009-08-24 18:20:57.993823 [INFO] mod_enum.c:808 ENUM Reloaded
> 2009-08-24 18:20:57.993823 [INFO] switch_time.c:661 Timezone reloaded 530
> definitions
>
> Event-Name: TRAP
> Core-UUID: f1764d44-a470-834c-9a07-c0225a65232d
> FreeSWITCH-Hostname: ProfNest
> FreeSWITCH-IPv4: 192.168.0.2
> FreeSWITCH-IPv6: %3A%3A1
> Event-Date-Local: 2009-08-24%2018%3A22%3A54
> Event-Date-GMT: Mon,%2024%20Aug%202009%2015%3A22%3A54%20GMT
> Event-Date-Timestamp: 1251127374637823
> Event-Calling-File: switch_nat.c
> Event-Calling-Function: switch_nat_multicast_runtime
> Event-Calling-Line-Number: 308
> condition: network-address-change
> network-address-previous-v4: 77.122.60.138
> network-address-change-v4:
> %?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?C%?Ch
>
>
> Bye.
>
> Monday, August 24, 2009, 18:40 Sergey Popov.
> ---
> * E-mail prof at ukrpost.net * ICQ 2831794 *
>
> _______________________________________________
> FreeSWITCH-dev mailing list
> FreeSWITCH-dev at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
> http://www.freeswitch.org
>
>
--
Anthony Minessale II
FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire
AIM: anthm
MSN:anthony_minessale at hotmail.com <MSN%3Aanthony_minessale at hotmail.com>
GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com<PAYPAL%3Aanthony.minessale at gmail.com>
IRC: irc.freenode.net #freeswitch
FreeSWITCH Developer Conference
sip:888 at conference.freeswitch.org <sip%3A888 at conference.freeswitch.org>
iax:guest at conference.freeswitch.org/888
googletalk:conf+888 at conference.freeswitch.org<googletalk%3Aconf%2B888 at conference.freeswitch.org>
pstn:213-799-1400
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-dev/attachments/20090824/b0295851/attachment.html
More information about the FreeSWITCH-dev
mailing list