Summary: | ASTERISK-05275: wrong peer name (IP address/port) looked up for devicestate changes during incoming calls | ||
Reporter: | scaredycat (scaredycat) | Labels: | |
Date Opened: | 2005-10-10 12:46:41 | Date Closed: | 2008-01-15 15:50:53.000-0600 |
Priority: | Minor | Regression? | No |
Status: | Closed/Complete | Components: | Core/General |
Versions: | Frequency of Occurrence | ||
Related Issues: | |||
Environment: | Attachments: | ||
Description: | Oct 10 19:33:39 DEBUG[17583]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '192.168.72.202:4569' This is incorrect, and will never match, since name isn't the ip/port - I believe that this is the reason callerid is not being pulled from the db. The problem occurs at call time, iax devices register fine but this occurs when they make a call. Tested with 2 softphones, iaxy, another asterisk box and an iax ata ****** ADDITIONAL INFORMATION ****** Normal behaviour Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17583]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17583]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17583]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:317 update_mysql: MySQL RealTime: Update SQL: UPDATE iax_buddies SET ipaddr = '192.168.72.202', port = '4569', regseconds = '1128965864' WHERE name = '1234567884' Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:37:44 DEBUG[17599]: res_config_mysql.c:336 update_mysql: MySQL RealTime: Updated 1 rows on table: iax_buddies Oct 10 19:37:44 NOTICE[17599]: chan_iax2.c:5589 update_registry: Restricting registration for peer '1234567884' to 60 seconds (requested 300) Oct 10 19:37:44 DEBUG[17583]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Incorrect behaviour Oct 10 19:39:10 DEBUG[17599]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567884' Oct 10 19:39:10 DEBUG[17599]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. -- Accepting AUTHENTICATED call from 192.168.72.202: > requested format = gsm, > requested prefs = (), > actual format = gsm, > host prefs = (gsm), > priority = mine ***** HERE ****** Oct 10 19:39:10 DEBUG[17583]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '192.168.72.202:4569' ***** HERE ****** -- Executing SetCallerID("IAX2/192.168.72.202:4569-1", "") in new stack -- Executing MaxTime("IAX2/192.168.72.202:4569-1", "") in new stack Oct 10 19:39:10 DEBUG[17583]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. -- Executing bDial("IAX2/192.168.72.202:4569-1", "SIP/1234567881&IAX2/1234567881||L(5658968350.000000:40000:20000)") in new stack -- Limit Data: -- timelimit=2147483647 -- play_warning=40000 -- play_to_caller=yes -- play_to_callee=no -- warning_freq=20000 -- start_sound=UNDEF -- warning_sound=timeleft -- end_sound=UNDEF Oct 10 19:39:10 DEBUG[17628]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '1234567881' Oct 10 19:39:10 DEBUG[17628]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. -- SIP Seeding peer from astdb: '1234567881' at 1234567881@192.168.72.27:5060 for 60 Oct 10 19:39:10 DEBUG[17628]: chan_sip.c:1819 create_addr_from_peer: Setting NAT on RTP to 524288 Oct 10 19:39:10 DEBUG[17628]: chan_sip.c:2013 sip_call: Outgoing Call for 1234567881 -- Called 1234567881 Oct 10 19:39:10 DEBUG[17628]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM iax_buddies WHERE name = '1234567881' Oct 10 19:39:10 DEBUG[17628]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. Oct 10 19:39:10 NOTICE[17628]: app_bdial.c:1319 dial_exec_full: Unable to create channel of type 'IAX2' (cause 3 - No route to destination) Oct 10 19:39:10 DEBUG[17599]: chan_iax2.c:6568 socket_read: Ooh, voice format changed to 2 Oct 10 19:39:11 DEBUG[17596]: chan_sip.c:1405 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '6893b958161a22f3294b48a058a0a93b@192.168.72.187' Request 102: Found Oct 10 19:39:11 DEBUG[17596]: chan_sip.c:1405 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '6893b958161a22f3294b48a058a0a93b@192.168.72.187' Request 102: Found -- SIP/1234567881-4b0c is ringing Oct 10 19:39:11 DEBUG[17583]: res_config_mysql.c:123 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '1234567881' Oct 10 19:39:11 DEBUG[17583]: res_config_mysql.c:629 mysql_reconnect: MySQL RealTime: Everything is fine. -- SIP Seeding peer from astdb: '1234567881' at 1234567881@192.168.72.27:5060 for 60 sigmund*CLI> Actual db data: name,username,type,secret,md5secret,dbsecret,notransfer,inkeys,auth,accountcode,amaflags,callerid,context,defaultip,host,language,mailbox,permit,deny,qualify,disallow,allow,ipaddr,port,regseconds 1234567884,1234567884,friend,llama,NULL,NULL,yes,NULL,NULL,4363,NULL,1234567884,iax_users,NULL,dynamic,NULL,NULL,NULL,NULL,NULL,NULL,NULL,192.168.72.202,4569,1128963294 | ||
Comments: | By: Mark Spencer (markster) 2005-10-13 18:53:56 It's not important at all, but it's fixed in CVS head. By: Digium Subversion (svnbot) 2008-01-15 15:50:53.000-0600 Repository: asterisk Revision: 6770 U trunk/channels/chan_iax2.c ------------------------------------------------------------------------ r6770 | markster | 2008-01-15 15:50:53 -0600 (Tue, 15 Jan 2008) | 2 lines Fix naming on realtime peers (bug ASTERISK-5275) ------------------------------------------------------------------------ http://svn.digium.com/view/asterisk?view=rev&revision=6770 |