| 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@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@ukrpost.net * ICQ 2831794 * _______________________________________________ FreeSWITCH-dev mailing list FreeSWITCH-dev@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@hotmail.com GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com IRC: irc.freenode.net #freeswitch FreeSWITCH Developer Conference sip:888@conference.freeswitch.org iax:guest@conference.freeswitch.org/888 googletalk:conf+888@conference.freeswitch.org pstn:213-799-1400 |