[Home]

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:41Date Closed:2008-01-15 15:50:53.000-0600
Priority:MinorRegression?No
Status:Closed/CompleteComponents: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