Stefan:
Appreciate your time on looking into it.I found following reg caching related 
logs: root@debian-64:~# grep RegisterCache.cpp regcache.log [#7f5973eb2700/676] 
[update, RegisterCache.cpp:402] DEBUG:  inserted new AOR 
'sip:[email protected]' [#7f5973eb2700/676] [update, RegisterCache.cpp:418] 
DEBUG:  inserted new binding: 'sip:[email protected]:9960' -> 
'b0ced53df78e9e34' [#7f5973eb2700/676] [update, RegisterCache.cpp:445] DEBUG:  
inserting alias map entry: 'b0ced53df78e9e34' -> 
'sip:[email protected]:9960' [#7f5973eb2700/676] [onUpdate, 
RegisterCache.cpp:178] DEBUG:  update: 
aor='sip:[email protected]';alias='b0ced53df78e9e34';expires=1410492335 
[#7f59732a6700/688] [onUpdate, RegisterCache.cpp:183] DEBUG:  update: 
alias='b0ced53df78e9e34';ua_expires=1410488843
See attachment for sems starting and then single user registration through log 
files.

Thanks and Best RegardsYanchong Wang

> Date: Thu, 11 Sep 2014 18:57:08 +0200
> From: [email protected]
> To: [email protected]; [email protected]
> Subject: Re: [Sems] Can not make call between users registered through sbc
> 
> Hello,
> 
> WangYanchong wrote on 09/10/2014 11:52 AM:
> > Dear SEMS experts:
> > 
> > I've spent several days studying SEMS presentations in kamailio world
> > 2014, and saw the reg caching features very attractive. After several
> > trial of installation, I found the code from github seems have more
> > new features including sbc's reg_caching. After successful compilation
> the code from github (https://github.com/sems-server) should always be
> the same as the code from the primary SEMS repository
> (http://git.sip-router.org/cgi-bin/gitweb.cgi?p=sems;a=summary)
> 
> 
> > and deployment as following, it looks like the reg_caching feature
> > work as expected.
> > 
> > However, when I tried to make a call between phones registered through
> > sbc(192.168.131.1/192.168.131.128) with active sbc profile use
> > transparent.sbcprofile.conf.
> > I always got a 404 error from sems sbc, I can not understand why it
> > does not relay the invitation request to the kamailio server(which is
> > the target registrar server with address 192.168.130.126:5060), and
> > just send out 404 to phones.
> > 
> > Here attached all my configuration files and logs.
> are you sure that the user registered before through the sbc? what
> does the log file look like when you register through the sbc?
> 
> you should see some things like RegCacheLogHandler update: alias='...'
> etc.
> 
> you could also add some more debugging code to
> SBCCallProfile::retarget to see what's going on.
> 
> hth
> Stefan
                                                                                
  
root@debian-64:~# sems -E -D 3
 [#7f597766f720/670] [init_logging, log.cpp:180] INFO:  Logging initialized
 [#7f597766f720/670] [readConfiguration, AmConfig.cpp:299] DEBUG:  Reading 
configuration...
 [#7f597766f720/670] [fillSysIntfList, AmConfig.cpp:995] DEBUG:  
iface='lo';ip='127.0.0.1';flags=0x10049
 [#7f597766f720/670] [fillSysIntfList, AmConfig.cpp:995] DEBUG:  
iface='eth0';ip='192.168.131.1';flags=0x11043
 [#7f597766f720/670] [fillSysIntfList, AmConfig.cpp:995] DEBUG:  
iface='eth1';ip='192.168.131.128';flags=0x11043
 [#7f597766f720/670] [fillSysIntfList, AmConfig.cpp:995] DEBUG:  
iface='lo';ip='::1';flags=0x10049
 [#7f597766f720/670] [fixIface2IP, AmConfig.cpp:1055] DEBUG:  dev_name = 'eth0'
Configuration:
       log level:           DEBUG (3)
       log to stderr:       yes
       configuration file:  /usr/local/etc/sems/sems.conf
       plug-in path:        /usr/local/lib/sems/plug-in/
       daemon mode:         no
       daemon UID:          <not set>
       daemon GID:          <not set>
       application:         sbc

 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1176] INFO:  Signaling interfaces:
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1186] INFO:        (0) 
name='intern';LocalIP='192.168.131.1';LocalPort='5060';PublicIP='';TCP=2000/3600000
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1186] INFO:        (1) 
name='extern';LocalIP='192.168.131.128';LocalPort='5060';PublicIP='192.168.131.128';TCP=2000/3600000
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1189] INFO:  Signaling address map:
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1198] INFO:        192.168.131.1 
-> intern
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1198] INFO:        192.168.131.128 
-> extern
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1202] INFO:  Media interfaces:
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1211] INFO:        (0) 
name='intern';LocalIP='192.168.131.1';Ports=[2000;5000];PublicIP=''
 [#7f597766f720/670] [dump_Ifs, AmConfig.cpp:1211] INFO:        (1) 
name='extern';LocalIP='192.168.131.128';Ports=[2000;5000];PublicIP='192.168.131.128'
 [#7f597766f720/670] [main, sems.cpp:561] INFO:  Starting application timer 
scheduler
 [#7f597766f720/670] [main, sems.cpp:564] INFO:  Starting session container
 [#7f597766f720/670] [main, sems.cpp:572] INFO:  Starting media processor
 [#7f597766f720/670] [init, AmMediaProcessor.cpp:67] DEBUG:  Starting 1 
MediaProcessorThreads.
 [#7f597766f720/670] [main, sems.cpp:582] INFO:  Starting RTP receiver
 [#7f597766f720/670] [main, sems.cpp:585] INFO:  Starting SIP stack (control 
interface)
 [#7f597766f720/670] [load, SipCtrlInterface.cpp:185] DEBUG:  
accept_fr_without_totag = yes
 [#7f597766f720/670] [load, SipCtrlInterface.cpp:192] DEBUG:  default_bl_ttl = 
60000
 [#7f597587f700/674] [_start, AmThread.cpp:65] DEBUG:  Thread 140022200661760 
is starting.
 [#7f597766f720/670] [load, SipCtrlInterface.cpp:203] DEBUG:  log_raw_messages 
level = 3
 [#7f597766f720/670] [load, SipCtrlInterface.cpp:209] DEBUG:  
log_parsed_messages = yes
 [#7f597766f720/670] [bind, udp_trsp.cpp:133] DEBUG:  UDP transport bound to 
192.168.131.1/5060
 [#7f5975980700/673] [_start, AmThread.cpp:65] DEBUG:  Thread 140022201714432 
is starting.
 [#7f597766f720/670] [bind, udp_trsp.cpp:133] DEBUG:  UDP transport bound to 
192.168.131.128/5060
 [#7f597766f720/670] [bind, tcp_trsp.cpp:660] DEBUG:  TCP transport bound to 
192.168.131.1/5060
 [#7f597766f720/670] [bind, tcp_trsp.cpp:660] DEBUG:  TCP transport bound to 
192.168.131.128/5060
 [#7f5975a81700/672] [_start, AmThread.cpp:65] DEBUG:  Thread 140022202767104 
is starting.
 [#7f597766f720/670] [main, sems.cpp:590] INFO:  Loading plug-ins
 [#7f597766f720/670] [init, AmPlugIn.cpp:159] DEBUG:  adding built-in codecs...
 [#7f597766f720/670] [addCodec, AmPlugIn.cpp:660] DEBUG:  codec id 0 inserted
 [#7f597766f720/670] [addCodec, AmPlugIn.cpp:660] DEBUG:  codec id 6 inserted
 [#7f597766f720/670] [addPayload, AmPlugIn.cpp:700] DEBUG:  payload 
'telephone-event/8000' inserted with id 96 and order 196
 [#7f597766f720/670] [load, AmPlugIn.cpp:214] INFO:  AmPlugIn: loading modules: 
'monitoring;session_timer;sbc;xmlrpc2di;stats;registrar_client;uac_auth'
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//monitoring.so...
 [#7f597766f720/670] [loadDiPlugIn, AmPlugIn.cpp:622] DEBUG:  component 
'monitoring' loaded.
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//session_timer.so...
 [#7f597766f720/670] [loadSehPlugIn, AmPlugIn.cpp:590] DEBUG:  session 
component 'session_timer' loaded.
 [#7f597766d700/671] [_start, AmThread.cpp:65] DEBUG:  Thread 140022232045312 
is starting.
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//sbc.so...
 [#7f597766f720/670] [loadPlugIn, AmPlugIn.cpp:281] DEBUG:  using RTLD_NOW | 
RTLD_GLOBAL to dlopen '/usr/local/lib/sems/plug-in//sbc.so'
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//xmlrpc2di.so...
 [#7f597766f720/670] [loadDiPlugIn, AmPlugIn.cpp:622] DEBUG:  component 
'xmlrpc2di' loaded.
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//stats.so...
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//registrar_client.so...
 [#7f597766f720/670] [loadPlugIn, AmPlugIn.cpp:281] DEBUG:  using RTLD_NOW | 
RTLD_GLOBAL to dlopen '/usr/local/lib/sems/plug-in//registrar_client.so'
 [#7f597766f720/670] [loadDiPlugIn, AmPlugIn.cpp:622] DEBUG:  component 
'registrar_client' loaded.
 [#7f597766f720/670] [load, AmPlugIn.cpp:231] DEBUG:  loading 
/usr/local/lib/sems/plug-in//uac_auth.so...
 [#7f597766f720/670] [loadPlugIn, AmPlugIn.cpp:281] DEBUG:  using RTLD_NOW | 
RTLD_GLOBAL to dlopen '/usr/local/lib/sems/plug-in//uac_auth.so'
 [#7f597766f720/670] [loadSehPlugIn, AmPlugIn.cpp:590] DEBUG:  session 
component 'uac_auth' loaded.
 [#7f597766f720/670] [loadDiPlugIn, AmPlugIn.cpp:622] DEBUG:  component 
'uac_auth' loaded.
 [#7f597766f720/670] [load, AmPlugIn.cpp:240] DEBUG:  AmPlugIn: modules loaded.
 [#7f597766f720/670] [load, AmPlugIn.cpp:241] DEBUG:  Initializing 8 plugins...
 [#7f597766f720/670] [onLoad, Monitoring.cpp:77] DEBUG:  Running garbage 
collection for monitoring every 10 seconds
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:405] INFO:  
SBC: loaded SBC profile 'transparent' - MD5: c4e28d65055bc079268c5d4eca7099f2
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:410] INFO:  
SBC:      RURI      = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:411] INFO:  
SBC:      RURI-host = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:412] INFO:  
SBC:      From = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:413] INFO:  
SBC:      To   = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:418] INFO:  
SBC:      Call-ID   = '$ci_leg2'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:421] INFO:  
SBC:      force outbound proxy: yes
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:422] INFO:  
SBC:      outbound proxy = 'sip:192.168.130.126:5060'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:425] INFO:  
SBC:      outbound interface = 'extern'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:432] INFO:  
SBC:      A leg force outbound proxy: no
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:433] INFO:  
SBC:      A leg outbound proxy = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:437] INFO:  
SBC:      next hop = 192.168.130.126:5060 (all reqs, not fixed)
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:449] INFO:  
SBC:      header filter  is blacklist, 2 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:455] INFO:  
SBC:      message filter is disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:462] INFO:  
SBC:      SDP filter is enabled, whitelist, 6 items in list, not anonymizing SDP
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:468] INFO:  
SBC:      SDP alines-filter is disabled, disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:474] INFO:  
SBC:      SDP filter is disabled, disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:476] INFO:  
SBC:      fixing Replaces in INVITE: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:477] INFO:  
SBC:      fixing Replaces in REFER: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:480] INFO:  
SBC:      RTP relay enabled: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:484] INFO:  
SBC:      RTP force symmetric RTP: yes
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:487] INFO:  
SBC:      P-MsgFlags symmetric RTP detection enabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:490] INFO:  
SBC:      RTP Relay interface A leg 'intern'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:493] INFO:  
SBC:      RTP Relay interface B leg 'extern'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:497] INFO:  
SBC:      RTP Relay transparent seqno
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:499] INFO:  
SBC:      RTP Relay transparent SSRC
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:501] INFO:  
SBC:      RTP Relay RTP DTMF filtering disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:503] INFO:  
SBC:      RTP Relay RTP DTMF detection disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:507] INFO:  
SBC:      SST on A leg enabled: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:510] INFO:  
SBC:              session_expires=120
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:512] INFO:  
SBC:              minimum_timer=90
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:514] INFO:  
SBC:              maximum_timer=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:516] INFO:  
SBC:              session_refresh_method=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:518] INFO:  
SBC:              accept_501_reply=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:521] INFO:  
SBC:      SST on B leg enabled: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:524] INFO:  
SBC:              session_expires=120
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:526] INFO:  
SBC:              minimum_timer=90
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:528] INFO:  
SBC:              maximum_timer=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:530] INFO:  
SBC:              session_refresh_method=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:532] INFO:  
SBC:              accept_501_reply=
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:535] INFO:  
SBC:      SIP auth enabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:536] INFO:  
SBC:      SIP auth for A leg disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:537] INFO:  
SBC:      SIP UAS auth for B leg disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:560] INFO:  
SBC:      append headers 'P-Source-IP: $si\r\nP-Source-Port: $sp\r\n'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:563] INFO:  
SBC:      reg-caching: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:564] INFO:  
SBC:      min_reg_expires: 3600
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:565] INFO:  
SBC:      max_ua_expires: 60
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1453] INFO:  SBC:      A 
leg codec preference: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1454] INFO:  SBC:      A 
leg prefer existing codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1455] INFO:  SBC:      B 
leg codec preference: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1456] INFO:  SBC:      B 
leg prefer existing codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1546] INFO:  SBC:      
transcoder audio codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1547] INFO:  SBC:      
callee codec capabilities: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1548] INFO:  SBC:      
enable transcoder: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1549] INFO:  SBC:      
norelay audio codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1550] INFO:  SBC:      
norelay audio codecs (aleg): 
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:405] INFO:  
SBC: loaded SBC profile 'symmetricrtp' - MD5: df643b9fd4232a6272f13d47ed935a6f
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:410] INFO:  
SBC:      RURI      = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:411] INFO:  
SBC:      RURI-host = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:412] INFO:  
SBC:      From = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:413] INFO:  
SBC:      To   = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:418] INFO:  
SBC:      Call-ID   = '$ci_leg_sems'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:421] INFO:  
SBC:      force outbound proxy: no
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:422] INFO:  
SBC:      outbound proxy = 'sip:192.168.130.126:5060'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:425] INFO:  
SBC:      outbound interface = 'extern'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:432] INFO:  
SBC:      A leg force outbound proxy: no
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:433] INFO:  
SBC:      A leg outbound proxy = ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:437] INFO:  
SBC:      next hop = 192.168.130.126:5060 (all reqs, not fixed)
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:449] INFO:  
SBC:      header filter  is disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:455] INFO:  
SBC:      message filter is disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:462] INFO:  
SBC:      SDP filter is disabled, disabled, 0 items in list, not anonymizing SDP
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:468] INFO:  
SBC:      SDP alines-filter is disabled, disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:474] INFO:  
SBC:      SDP filter is disabled, disabled, 0 items in list
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:476] INFO:  
SBC:      fixing Replaces in INVITE: ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:477] INFO:  
SBC:      fixing Replaces in REFER: ''
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:480] INFO:  
SBC:      RTP relay enabled: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:484] INFO:  
SBC:      RTP force symmetric RTP: yes
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:490] INFO:  
SBC:      RTP Relay interface A leg 'intern'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:493] INFO:  
SBC:      RTP Relay interface B leg 'default'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:497] INFO:  
SBC:      RTP Relay transparent seqno
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:499] INFO:  
SBC:      RTP Relay transparent SSRC
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:501] INFO:  
SBC:      RTP Relay RTP DTMF filtering disabled
 [#7f5973fb3700/675] [_start, AmThread.cpp:65] DEBUG:  Thread 140022174660352 
is starting.
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:503] INFO:  
SBC:      RTP Relay RTP DTMF detection disabled
 [#7f5973fb3700/675] [run, Monitoring.cpp:723] DEBUG:  running 
MonitorGarbageCollector thread
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:507] INFO:  
SBC:      SST on A leg enabled: 'no'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:521] INFO:  
SBC:      SST on B leg enabled: 'no'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:535] INFO:  
SBC:      SIP auth disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:536] INFO:  
SBC:      SIP auth for A leg disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:537] INFO:  
SBC:      SIP UAS auth for B leg disabled
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:563] INFO:  
SBC:      reg-caching: 'yes'
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:564] INFO:  
SBC:      min_reg_expires: 3600
 [#7f597766f720/670] [readFromConfiguration, SBCCallProfile.cpp:565] INFO:  
SBC:      max_ua_expires: 60
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1453] INFO:  SBC:      A 
leg codec preference: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1454] INFO:  SBC:      A 
leg prefer existing codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1455] INFO:  SBC:      B 
leg codec preference: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1456] INFO:  SBC:      B 
leg prefer existing codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1546] INFO:  SBC:      
transcoder audio codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1547] INFO:  SBC:      
callee codec capabilities: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1548] INFO:  SBC:      
enable transcoder: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1549] INFO:  SBC:      
norelay audio codecs: 
 [#7f597766f720/670] [infoPrint, SBCCallProfile.cpp:1550] INFO:  SBC:      
norelay audio codecs (aleg): 
 [#7f597766f720/670] [onLoad, SBC.cpp:218] INFO:  SBC: active profile: 
'symmetricrtp'
 [#7f597766f720/670] [onLoad, SBC.cpp:235] DEBUG:  OPTIONS messages handled by 
the core: no
 [#7f597766f720/670] [registerApplication, AmPlugIn.cpp:768] DEBUG:  
Application 'sbc' registered.
 [#7f597766f720/670] [registerDIInterface, AmPlugIn.cpp:827] DEBUG:  DI 
Interface 'sbc' registered.
 [#7f597766f720/670] [addThreads, AmEventQueueProcessor.cpp:83] DEBUG:  
starting 1 session processor threads
 [#7f597766f720/670] [addThreads, AmEventQueueProcessor.cpp:90] DEBUG:  now 1 
session processor threads running
 [#7f597766f720/670] [load, XMLRPC2DI.cpp:96] DEBUG:  Running multi-threaded 
XMLRPC server with 5 threads
 [#7f597766f720/670] [load, XMLRPC2DI.cpp:106] DEBUG:  retrying failed server 
after 10 seconds
 [#7f597766f720/670] [load, XMLRPC2DI.cpp:131] DEBUG:  binding on ANY interface
 [#7f597766f720/670] [load, XMLRPC2DI.cpp:151] DEBUG:  direct_export 
interfaces: sbc
 [#7f597766f720/670] [load, XMLRPC2DI.cpp:161] INFO:  XMLRPC Server: Enabling 
builtin method 'di'.
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:360] INFO:  XMLRPC Server: 
enabled builtin method 'calls'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:361] INFO:  XMLRPC Server: 
enabled builtin method 'get_loglevel'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:362] INFO:  XMLRPC Server: 
enabled builtin method 'set_loglevel'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:363] INFO:  XMLRPC Server: 
enabled builtin method 'get_shutdownmode'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:364] INFO:  XMLRPC Server: 
enabled builtin method 'set_shutdownmode'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:365] INFO:  XMLRPC Server: 
enabled builtin method 'get_callsavg'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:366] INFO:  XMLRPC Server: 
enabled builtin method 'get_callsmax'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:367] INFO:  XMLRPC Server: 
enabled builtin method 'get_cpsavg'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:368] INFO:  XMLRPC Server: 
enabled builtin method 'get_cpsmax'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:369] INFO:  XMLRPC Server: 
enabled builtin method 'get_cpslimit'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:370] INFO:  XMLRPC Server: 
enabled builtin method 'set_cpslimit'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'listProfiles'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.listProfiles'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'reloadProfiles'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.reloadProfiles'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'reloadProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.reloadProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'loadProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.loadProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'getActiveProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.getActiveProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'setActiveProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.setActiveProfile'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'getRegexMapNames'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.getRegexMapNames'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'setRegexMap'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.setRegexMap'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'loadCallcontrolModules'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.loadCallcontrolModules'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'postControlCmd'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.postControlCmd'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:424] INFO:  XMLRPC Server: 
enabling method 'printCallStats'
 [#7f597766f720/670] [registerMethods, XMLRPC2DI.cpp:430] INFO:  XMLRPC Server: 
enabling method 'sbc.printCallStats'
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:384] INFO:  Initialized 
XMLRPC2DIServer with: 
 [#7f597766f720/670] [XMLRPC2DIServer, XMLRPC2DI.cpp:386] INFO:      IP = ANY   
          port = 8090
 [#7f597766f720/670] [initialize, XMLRPC2DI.cpp:448] DEBUG:  Binding 
XMLRPC2DIServer to port 8090 
 [#7f597766f720/670] [init, StatsUDPServer.cpp:146] DEBUG:  udp_port = 5040
 [#7f597766f720/670] [init, StatsUDPServer.cpp:185] INFO:  stats server 
listening on 127.0.0.1:5040
 [#7f597766f720/670] [loadFile, AmConfigReader.cpp:82] WARNING:  could not open 
configuration file '/usr/local/etc/sems/etc/uac_auth.conf': No such file or 
directory
 [#7f59738ac700/682] [_start, AmThread.cpp:65] DEBUG:  Thread 140022167291648 
is starting.
 [#7f59739ad700/681] [_start, AmThread.cpp:65] DEBUG:  Thread 140022168344320 
is starting.
 [#7f5973aae700/680] [_start, AmThread.cpp:65] DEBUG:  Thread 140022169396992 
is starting.
 [#7f5973baf700/679] [_start, AmThread.cpp:65] DEBUG:  Thread 140022170449664 
is starting.
 [#7f5973cb0700/678] [_start, AmThread.cpp:65] DEBUG:  Thread 140022171502336 
is starting.
 [#7f59737ab700/683] [_start, AmThread.cpp:65] DEBUG:  Thread 140022166238976 
is starting.
 [#7f5973db1700/677] [_start, AmThread.cpp:65] DEBUG:  Thread 140022172555008 
is starting.
 [#7f59736aa700/684] [_start, AmThread.cpp:65] DEBUG:  Thread 140022165186304 
is starting.
 [#7f59735a9700/685] [_start, AmThread.cpp:65] DEBUG:  Thread 140022164133632 
is starting.
 [#7f597766f720/670] [onLoad, UACAuth.cpp:99] WARNING:  Could not open 
'/usr/local/etc/sems/etc/uac_auth.conf', assuming that default values are fine
 [#7f59737ab700/683] [run, XMLRPC2DI.cpp:461] DEBUG:  starting 
XMLRPC2DIServer...
 [#7f59736aa700/684] [run, StatsUDPServer.cpp:195] DEBUG:  running 
StatsUDPServer...
 [#7f59735a9700/685] [run, SIPRegistrarClient.cpp:71] DEBUG:  
SIPRegistrarClient starting...
 [#7f597766f720/670] [setServerSecret, UACAuth.cpp:635] DEBUG:  Server Nonce 
secret set
 [#7f597766f720/670] [loadFile, AmConfigReader.cpp:82] WARNING:  could not open 
configuration file '/usr/local/etc/sems/etc/uac_auth.conf': No such file or 
directory
 [#7f597766f720/670] [onLoad, UACAuth.cpp:99] WARNING:  Could not open 
'/usr/local/etc/sems/etc/uac_auth.conf', assuming that default values are fine
 [#7f597766f720/670] [setServerSecret, UACAuth.cpp:635] DEBUG:  Server Nonce 
secret set
 [#7f597766f720/670] [initMonitoring, AmSessionContainer.cpp:119] INFO:  
monitoring enabled
 [#7f597766f720/670] [run, SipCtrlInterface.cpp:381] DEBUG:  Starting SIP 
control interface
 [#7f5973eb2700/676] [_start, AmThread.cpp:65] DEBUG:  Thread 140022173607680 
is starting.
 [#7f5972ca0700/694] [_start, AmThread.cpp:65] DEBUG:  Thread 140022154659584 
is starting.
 [#7f5972ca0700/694] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.128:5060
 [#7f5972b9f700/695] [_start, AmThread.cpp:65] DEBUG:  Thread 140022153606912 
is starting.
 [#7f5972a9e700/696] [_start, AmThread.cpp:65] DEBUG:  Thread 140022152554240 
is starting.
 [#7f5972fa3700/691] [_start, AmThread.cpp:65] DEBUG:  Thread 140022157817600 
is starting.
 [#7f5972ea2700/692] [_start, AmThread.cpp:65] DEBUG:  Thread 140022156764928 
is starting.
 [#7f5972da1700/693] [_start, AmThread.cpp:65] DEBUG:  Thread 140022155712256 
is starting.
 [#7f5972b9f700/695] [run, tcp_trsp.cpp:807] INFO:  Started SIP server TCP 
transport on 192.168.131.1:5060
 [#7f5972a9e700/696] [run, tcp_trsp.cpp:807] INFO:  Started SIP server TCP 
transport on 192.168.131.128:5060
 [#7f5972fa3700/691] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.128:5060
 [#7f5972ea2700/692] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.128:5060
 [#7f5972da1700/693] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.128:5060
 [#7f59730a4700/690] [_start, AmThread.cpp:65] DEBUG:  Thread 140022158870272 
is starting.
 [#7f59730a4700/690] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.1:5060
 [#7f59731a5700/689] [_start, AmThread.cpp:65] DEBUG:  Thread 140022159922944 
is starting.
 [#7f59731a5700/689] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.1:5060
 [#7f59732a6700/688] [_start, AmThread.cpp:65] DEBUG:  Thread 140022160975616 
is starting.
 [#7f59732a6700/688] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.1:5060
 [#7f59733a7700/687] [_start, AmThread.cpp:65] DEBUG:  Thread 140022162028288 
is starting.
 [#7f59733a7700/687] [run, udp_trsp.cpp:312] INFO:  Started SIP server UDP 
transport on 192.168.131.1:5060
 [#7f59734a8700/686] [_start, AmThread.cpp:65] DEBUG:  Thread 140022163080960 
is starting.
 [#7f5972296700/704] [_start, AmThread.cpp:65] DEBUG:  Thread 140022144132864 
is starting.
 [#7f5972599700/701] [_start, AmThread.cpp:65] DEBUG:  Thread 140022147290880 
is starting.
 [#7f5972498700/702] [_start, AmThread.cpp:65] DEBUG:  Thread 140022146238208 
is starting.
 [#7f5972397700/703] [_start, AmThread.cpp:65] DEBUG:  Thread 140022145185536 
is starting.
 [#7f597269a700/700] [_start, AmThread.cpp:65] DEBUG:  Thread 140022148343552 
is starting.
 [#7f597279b700/699] [_start, AmThread.cpp:65] DEBUG:  Thread 140022149396224 
is starting.
 [#7f597289c700/698] [_start, AmThread.cpp:65] DEBUG:  Thread 140022150448896 
is starting.
 [#7f597299d700/697] [_start, AmThread.cpp:65] DEBUG:  Thread 140022151501568 
is starting.

































 [#7f59730a4700/690] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--
REGISTER sip:polylink.net SIP/2.0
To: phone03<sip:[email protected]>
From: phone03<sip:[email protected]>;tag=7750b76d
Via: SIP/2.0/UDP 
192.168.131.182:9960;branch=z9hG4bK-d87543-756816643-1--d87543-;rport
Call-ID: 52403c6d4d2a8f21
CSeq: 1 REGISTER
Contact: <sip:[email protected]:9960>
Expires: 60
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
Content-Length: 0

--++--
 [#7f59730a4700/690] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f59730a4700/690] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed 
branch: z9hG4bK-d87543-756816643-1--d87543-
 [#7f59730a4700/690] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
 [#7f59730a4700/690] [match_request, trans_table.cpp:99] DEBUG:  Matching 
REGISTER request
 [#7f59730a4700/690] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port (9960) to int (9960)
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:765] DEBUG:  
Received new request from <192.168.131.182:9960/udp> on intf #0
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:769] DEBUG:  
req.method = <REGISTER>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:772] DEBUG:  
req.r_uri = <sip:polylink.net>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:773] DEBUG:  
req.from_uri = <sip:[email protected]:9960>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:774] DEBUG:  
req.from = <phone03 <sip:[email protected]>>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:775] DEBUG:  
req.to = <phone03<sip:[email protected]>>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:776] DEBUG:  
req.callid = <52403c6d4d2a8f21>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:777] DEBUG:  
req.from_tag = <7750b76d>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:778] DEBUG:  
req.to_tag = <>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:779] DEBUG:  
cseq = <1>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:780] DEBUG:  
req.route = <>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:781] DEBUG:  
hdrs = <Expires: 60
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
>
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:782] DEBUG:  
body-ct = <>
 [#7f59730a4700/690] [handleSipMsg, AmSipDispatcher.cpp:105] DEBUG:  method: 
`REGISTER' [8].
 [#7f59730a4700/690] [onOoDRequest, SBC.cpp:359] DEBUG:  processing message 
REGISTER sip:polylink.net
 [#7f59730a4700/690] [getActiveProfileMatch, SBC.cpp:278] DEBUG:  active 
profile = symmetricrtp
 [#7f59730a4700/690] [getActiveProfileMatch, SBC.cpp:290] DEBUG:  using call 
profile 'symmetricrtp' (from matching active_profile rule 'symmetricrtp')
 [#7f59730a4700/690] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59730a4700/690] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59730a4700/690] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59730a4700/690] [initUAC, RegisterDialog.cpp:367] DEBUG:  
contact_hiding=0, reg_caching=1
 [#7f59730a4700/690] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f59730a4700/690] [initAor, RegisterDialog.cpp:351] DEBUG:  parsed AOR: 
'sip:[email protected]'
 [#7f59730a4700/690] [initUAC, RegisterDialog.cpp:387] DEBUG:  parsing 
contacts: '<sip:[email protected]:9960>'
 [#7f59730a4700/690] [parseContacts, RegisterDialog.cpp:33] DEBUG:  parsing 
contacts: '<sip:[email protected]:9960>'
 [#7f59730a4700/690] [parseContacts, RegisterDialog.cpp:52] DEBUG:  
successfully parsed contact [email protected]
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:173] DEBUG:  no alias 
in cache, created one
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:182] DEBUG:  using 
alias = 'b0ced53df78e9e34' for aor = 'sip:[email protected]' and uri = 
'sip:[email protected]:9960'
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:220] DEBUG:  
min_reg_expire = 3600
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:221] DEBUG:  
max_ua_expire = 60
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:222] DEBUG:  
contact_expires = 60
 [#7f59730a4700/690] [fixUacContacts, RegisterDialog.cpp:223] DEBUG:  
reg_expires = -1410488735
 [#7f59730a4700/690] [setOutboundInterface, AmBasicSipDialog.cpp:152] DEBUG:  
setting outbound interface to 1
 [#7f59730a4700/690] [apply_b_routing, SBCCallProfile.cpp:964] DEBUG:  set next 
hop to '192.168.130.126:5060' (1st_req=false,fixed=false)
 [#7f59730a4700/690] [apply_b_routing, SBCCallProfile.cpp:970] DEBUG:  
patch_ruri_next_hop = 0
 [#7f59730a4700/690] [replaceParameters, ParamReplacer.cpp:774] DEBUG:  Call-ID 
pattern replace: '$ci_leg_sems' -> '52403c6d4d2a8f21_leg_sems'
 [#7f59730a4700/690] [fixUacContactHosts, RegisterDialog.cpp:298] DEBUG:  
Patching host, port and transport for Contact-HF: host='192.168.131.128';port=''
 [#7f59730a4700/690] [onRxRequest, AmBasicSipDialog.cpp:308] DEBUG:  
AmBasicSipDialog::onRxRequest(req = REGISTER)
 [#7f59730a4700/690] [setOutboundInterface, AmBasicSipDialog.cpp:152] DEBUG:  
setting outbound interface to 0
 [#7f5973eb2700/676] [run, AmEventQueueProcessor.cpp:119] DEBUG:  running 
processing loop
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:192] DEBUG:  vv 
[52403c6d4d2a8f21_leg_sems|7358D5F2-5412599F000A2235-730A4700] 0 usages 
(active) vv
 [#7f5973eb2700/676] [onTxRequest, RegisterDialog.cpp:579] DEBUG:  method = 
REGISTER; hdrs = 'Expires: 60
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
'
 [#7f5973eb2700/676] [onTxRequest, RegisterDialog.cpp:591] DEBUG:  generated 
new contact: '<sip:[email protected]>;expires=3600'
 [#7f5973eb2700/676] [resolve_targets, resolver.cpp:1080] DEBUG:  
sip_destination: 192.168.130.126:5060/
 [#7f5973eb2700/676] [set_destination_ip, resolver.cpp:1005] DEBUG:  checking 
whether '192.168.130.126' is IP address...
 [#7f5973eb2700/676] [set_destination_ip, resolver.cpp:1063] DEBUG:  set 
destination to 192.168.130.126:5060
 [#7f5973eb2700/676] [debug, resolver.cpp:794] DEBUG:  target list:
 [#7f5973eb2700/676] [debug, resolver.cpp:801] DEBUG:   192.168.130.126:5060/ 
to target list
 [#7f5973eb2700/676] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port (5060) to int (5060)
 [#7f5973eb2700/676] [send_request, trans_layer.cpp:1264] DEBUG:  send_request 
to R-URI <sip:polylink.net>
 [#7f5973eb2700/676] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  
contact: <<sip:[email protected]>;expires=3600>
 [#7f5973eb2700/676] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f5973eb2700/676] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f5973eb2700/676] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed 
branch: z9hG4bKNgwfhahN
 [#7f5973eb2700/676] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
 [#7f5973eb2700/676] [send_request, trans_layer.cpp:1300] DEBUG:  Sending to 
192.168.130.126:5060 <REGISTER sip:polylink.net SIP/2.0
Via: SIP/2.0/UDP 192.168.131.128;branch=z9hG4bKNgwfhahN;rport
From: phone03 <sip:[email protected]>;tag=7358D5F2-5412599F000A2235-730A4700
To: phone03<sip:[email protected]>
CSeq: 10 REGISTER
Call-ID: 52403c6d4d2a8f21_leg_sems
Route: <sip:192.168.130.126:5060;lr>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
Contact: <sip:[email protected];transport=udp>;expires=3600
Content-Length: 0

...>
 [#7f5973eb2700/676] [send, udp_trsp.cpp:259] DEBUG: send  msg to 
192.168.130.126:5060
--++--
REGISTER sip:polylink.net SIP/2.0
Via: SIP/2.0/UDP 192.168.131.128;branch=z9hG4bKNgwfhahN;rport
From: phone03 <sip:[email protected]>;tag=7358D5F2-5412599F000A2235-730A4700
To: phone03<sip:[email protected]>
CSeq: 10 REGISTER
Call-ID: 52403c6d4d2a8f21_leg_sems
Route: <sip:192.168.130.126:5060;lr>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
Contact: <sip:[email protected];transport=udp>;expires=3600
Content-Length: 0

--++--
 [#7f5973eb2700/676] [send_request, trans_layer.cpp:1327] DEBUG:  
update_uac_request tt->_t =(nil)
 [#7f5973eb2700/676] [update_uac_request, trans_layer.cpp:2060] DEBUG:  
update_uac_request(t=0x898890)
 [#7f5973eb2700/676] [update_uac_request, trans_layer.cpp:2096] DEBUG:  
transport = 'udp'; is_reliable = 0
 [#7f5973eb2700/676] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type 
E at time=1777 (repeated=0)
 [#7f5973eb2700/676] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type 
F at time=3352 (repeated=0)
 [#7f5973eb2700/676] [send_request, trans_layer.cpp:1349] DEBUG:  logger = (nil)
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:208] DEBUG:  ^^ 
[52403c6d4d2a8f21_leg_sems|7358D5F2-5412599F000A2235-730A4700] 0 usages 
(active) ^^
 [#7f59730a4700/690] [handle_sip_request, SipCtrlInterface.cpp:788] DEBUG:  ^^ 
M [52403c6d4d2a8f21|] Ru SIP request REGISTER handled ^^
 [#7f5972195700/715] [_start, AmThread.cpp:65] DEBUG:  Thread 140022143080192 
is starting.
 [#7f5972ca0700/694] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.130.126:5060 vv
--++--
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.131.128;branch=z9hG4bKNgwfhahN;rport=5060
From: phone03 <sip:[email protected]>;tag=7358D5F2-5412599F000A2235-730A4700
To: phone03<sip:[email protected]>;tag=8a82f15ccfc3fc578d11bcaafa998011.0db7
CSeq: 10 REGISTER
Call-ID: 52403c6d4d2a8f21_leg_sems
Contact: <sip:[email protected];transport=udp>;expires=3600
Server: kamailio (4.1.5 (x86_64/linux))
Content-Length: 0

--++--
 [#7f5972ca0700/694] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed 
branch: z9hG4bKNgwfhahN
 [#7f5972ca0700/694] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
 [#7f5972ca0700/694] [process_rcvd_msg, trans_layer.cpp:1714] DEBUG:  Reply 
matched an existing transaction
 [#7f5972ca0700/694] [update_uac_reply, trans_layer.cpp:1772] DEBUG:  
update_uac_reply(reply code = 200, trans=0x898890)
 [#7f5972ca0700/694] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old 
timer of type E (this=0x897b70)
 [#7f5972ca0700/694] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old 
timer of type F (this=0x897a30)
 [#7f5972ca0700/694] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type 
K at time=2003 (repeated=0)
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:813] DEBUG:  
Received reply: 200 OK
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:814] DEBUG:  
reply.callid = <52403c6d4d2a8f21_leg_sems>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:815] DEBUG:  
reply.from_tag = <7358D5F2-5412599F000A2235-730A4700>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:816] DEBUG:  
reply.to_tag = <8a82f15ccfc3fc578d11bcaafa998011.0db7>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:817] DEBUG:  
reply.contact = 
<<sip:[email protected];transport=udp>;expires=3600>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:818] DEBUG:  
reply.to_uri = <sip:[email protected];transport=udp>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:819] DEBUG:  cseq 
= <10>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:820] DEBUG:  
reply.route = <>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:821] DEBUG:  hdrs 
= <Server: kamailio (4.1.5 (x86_64/linux))
>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:822] DEBUG:  
body-ct = <>
 [#7f5972ca0700/694] [handle_sip_reply, SipCtrlInterface.cpp:828] DEBUG:  ^^ M 
[52403c6d4d2a8f21_leg_sems|7358D5F2-5412599F000A2235-730A4700] ru SIP reply 200 
OK handled ^^
 [#7f5973eb2700/676] [run, AmEventQueueProcessor.cpp:119] DEBUG:  running 
processing loop
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:192] DEBUG:  vv 
[52403c6d4d2a8f21_leg_sems|7358D5F2-5412599F000A2235-730A4700] 0 usages 
(active) vv
 [#7f5973eb2700/676] [onRxReply, AmBasicSipDialog.cpp:449] DEBUG:  
onRxReply(rep = 200 OK): transaction found!
 [#7f5973eb2700/676] [onSipReply, RegisterDialog.cpp:442] DEBUG:  parsing 
server contact set 
'<sip:[email protected];transport=udp>;expires=3600'
 [#7f5973eb2700/676] [parseContacts, RegisterDialog.cpp:33] DEBUG:  parsing 
contacts: '<sip:[email protected];transport=udp>;expires=3600'
 [#7f5973eb2700/676] [parseContacts, RegisterDialog.cpp:52] DEBUG:  
successfully parsed contact [email protected]
 [#7f5973eb2700/676] [onSipReply, RegisterDialog.cpp:446] DEBUG:  Got 1 server 
contacts
 [#7f5973eb2700/676] [update, RegisterCache.cpp:402] DEBUG:  inserted new AOR 
'sip:[email protected]'
 [#7f5973eb2700/676] [update, RegisterCache.cpp:418] DEBUG:  inserted new 
binding: 'sip:[email protected]:9960' -> 'b0ced53df78e9e34'
 [#7f5973eb2700/676] [update, RegisterCache.cpp:445] DEBUG:  inserting alias 
map entry: 'b0ced53df78e9e34' -> 'sip:[email protected]:9960'
 [#7f5973eb2700/676] [onUpdate, RegisterCache.cpp:178] DEBUG:  update: 
aor='sip:[email protected]';alias='b0ced53df78e9e34';expires=1410492335
 [#7f5973eb2700/676] [onSipReply, RegisterDialog.cpp:525] DEBUG:  
reg_caching=1; alias_map.empty()=1
 [#7f5973eb2700/676] [onSipReply, RegisterDialog.cpp:555] DEBUG:  generated new 
contacts: '<sip:[email protected]:9960>;expires=60'
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:208] DEBUG:  ^^ 
[52403c6d4d2a8f21_leg_sems|7358D5F2-5412599F000A2235-730A4700] 0 usages (term) 
^^
 [#7f5973eb2700/676] [finalize, SBCSimpleRelay.cpp:222] DEBUG:  finalize(): 
tag=7358D5F2-5412599F000A2235-730A4700
 [#7f5973eb2700/676] [~SimpleRelayDialog, SBCSimpleRelay.cpp:72] DEBUG:  
~SimpleRelayDialog: local_tag = 7358D5F2-5412599F000A2235-730A4700
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:733] DEBUG:  callid = 
52403c6d4d2a8f21_leg_sems
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:734] DEBUG:  local_tag = 
7358D5F2-5412599F000A2235-730A4700
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:735] DEBUG:  uac_trans.size() 
= 0
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:743] DEBUG:  uas_trans.size() 
= 0
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:192] DEBUG:  vv 
[52403c6d4d2a8f21|6B6EA933-5412599F000A1C48-730A4700] 0 usages (active) vv
 [#7f5973eb2700/676] [reply, AmBasicSipDialog.cpp:583] DEBUG:  reply: 
transaction found!
 [#7f5973eb2700/676] [onTxReply, RegisterDialog.cpp:566] DEBUG:  code = 200; 
hdrs = 'Server: kamailio (4.1.5 (x86_64/linux))
Contact: <sip:[email protected]:9960>;expires=60
'
 [#7f5973eb2700/676] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  
contact: <<sip:[email protected]:9960>;expires=60>
 [#7f5973eb2700/676] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port (9960) to int (9960)
 [#7f5973eb2700/676] [send_reply, trans_layer.cpp:410] DEBUG:  reply_len = 412
 [#7f5973eb2700/676] [is_opt_set, transport.cpp:76] DEBUG:  
trsp_socket::socket_options = 0x2
 [#7f5973eb2700/676] [is_opt_set, transport.cpp:76] DEBUG:  
trsp_socket::socket_options = 0x2
 [#7f5973eb2700/676] [send_reply, trans_layer.cpp:585] DEBUG:  Sending to 
192.168.131.182:9960 <SIP/2.0 200 OK
To: phone03<sip:[email protected]...>
 [#7f5973eb2700/676] [send, udp_trsp.cpp:259] DEBUG: send  msg to 
192.168.131.182:9960
--++--
SIP/2.0 200 OK
To: phone03<sip:[email protected]>;tag=6B6EA933-5412599F000A1C48-730A4700
From: phone03<sip:[email protected]>;tag=7750b76d
Via: SIP/2.0/UDP 
192.168.131.182:9960;branch=z9hG4bK-d87543-756816643-1--d87543-;rport=9960
Call-ID: 52403c6d4d2a8f21
CSeq: 1 REGISTER
Server: kamailio (4.1.5 (x86_64/linux))
Contact: <sip:[email protected]:9960;transport=udp>;expires=60
Content-Length: 0

--++--
 [#7f5973eb2700/676] [update_uas_reply, trans_layer.cpp:2126] DEBUG:  
update_uas_reply(t=0x88f750)
 [#7f5973eb2700/676] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type 
J at time=3353 (repeated=0)
 [#7f5973eb2700/676] [processingCycle, SBCSimpleRelay.cpp:208] DEBUG:  ^^ 
[52403c6d4d2a8f21|6B6EA933-5412599F000A1C48-730A4700] 0 usages (term) ^^
 [#7f5973eb2700/676] [finalize, SBCSimpleRelay.cpp:222] DEBUG:  finalize(): 
tag=6B6EA933-5412599F000A1C48-730A4700
 [#7f5973eb2700/676] [~SimpleRelayDialog, SBCSimpleRelay.cpp:72] DEBUG:  
~SimpleRelayDialog: local_tag = 6B6EA933-5412599F000A1C48-730A4700
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:733] DEBUG:  callid = 
52403c6d4d2a8f21
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:734] DEBUG:  local_tag = 
6B6EA933-5412599F000A1C48-730A4700
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:735] DEBUG:  uac_trans.size() 
= 0
 [#7f5973eb2700/676] [dump, AmBasicSipDialog.cpp:743] DEBUG:  uas_trans.size() 
= 0
 [#7f59734a8700/686] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer 
expired: type=K, trans=0x898890, eta=2003, t=2003
 [#7f59734a8700/686] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old 
timer of type K (this=0x8912c0)
 [#7f59731a5700/689] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59732a6700/688] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59732a6700/688] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59732a6700/688] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59732a6700/688] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59733a7700/687] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59734a8700/686] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer 
expired: type=J, trans=0x88f750, eta=3353, t=3353
 [#7f59734a8700/686] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old 
timer of type J (this=0x891bb0)
 [#7f59730a4700/690] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59731a5700/689] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59731a5700/689] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59732a6700/688] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--
REGISTER sip:polylink.net SIP/2.0
To: phone03<sip:[email protected]>
From: phone03<sip:[email protected]>;tag=7750b76d
Via: SIP/2.0/UDP 
192.168.131.182:9960;branch=z9hG4bK-d87543-682559995-1--d87543-;rport
Call-ID: 52403c6d4d2a8f21
CSeq: 2 REGISTER
Contact: <sip:[email protected]:9960>
Expires: 60
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
Content-Length: 0

--++--
 [#7f59732a6700/688] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f59732a6700/688] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed 
branch: z9hG4bK-d87543-682559995-1--d87543-
 [#7f59732a6700/688] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
 [#7f59732a6700/688] [match_request, trans_table.cpp:99] DEBUG:  Matching 
REGISTER request
 [#7f59732a6700/688] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port (9960) to int (9960)
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:765] DEBUG:  
Received new request from <192.168.131.182:9960/udp> on intf #0
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:769] DEBUG:  
req.method = <REGISTER>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:772] DEBUG:  
req.r_uri = <sip:polylink.net>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:773] DEBUG:  
req.from_uri = <sip:[email protected]:9960>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:774] DEBUG:  
req.from = <phone03 <sip:[email protected]>>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:775] DEBUG:  
req.to = <phone03<sip:[email protected]>>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:776] DEBUG:  
req.callid = <52403c6d4d2a8f21>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:777] DEBUG:  
req.from_tag = <7750b76d>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:778] DEBUG:  
req.to_tag = <>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:779] DEBUG:  
cseq = <2>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:780] DEBUG:  
req.route = <>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:781] DEBUG:  
hdrs = <Expires: 60
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, 
INFO
User-Agent: eyeBeam release 3004t stamp 16741
>
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:782] DEBUG:  
body-ct = <>
 [#7f59732a6700/688] [handleSipMsg, AmSipDispatcher.cpp:105] DEBUG:  method: 
`REGISTER' [8].
 [#7f59732a6700/688] [onOoDRequest, SBC.cpp:359] DEBUG:  processing message 
REGISTER sip:polylink.net
 [#7f59732a6700/688] [getActiveProfileMatch, SBC.cpp:278] DEBUG:  active 
profile = symmetricrtp
 [#7f59732a6700/688] [getActiveProfileMatch, SBC.cpp:290] DEBUG:  using call 
profile 'symmetricrtp' (from matching active_profile rule 'symmetricrtp')
 [#7f59732a6700/688] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59732a6700/688] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59732a6700/688] [remove_empty_headers, SBCCallProfile.cpp:1191] DEBUG:  
SBCCallProfile::remove_empty_headers ''
 [#7f59732a6700/688] [initUAC, RegisterDialog.cpp:367] DEBUG:  
contact_hiding=0, reg_caching=1
 [#7f59732a6700/688] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port () to int (5060)
 [#7f59732a6700/688] [initAor, RegisterDialog.cpp:351] DEBUG:  parsed AOR: 
'sip:[email protected]'
 [#7f59732a6700/688] [initUAC, RegisterDialog.cpp:387] DEBUG:  parsing 
contacts: '<sip:[email protected]:9960>'
 [#7f59732a6700/688] [parseContacts, RegisterDialog.cpp:33] DEBUG:  parsing 
contacts: '<sip:[email protected]:9960>'
 [#7f59732a6700/688] [parseContacts, RegisterDialog.cpp:52] DEBUG:  
successfully parsed contact [email protected]
 [#7f59732a6700/688] [fixUacContacts, RegisterDialog.cpp:182] DEBUG:  using 
alias = 'b0ced53df78e9e34' for aor = 'sip:[email protected]' and uri = 
'sip:[email protected]:9960'
 [#7f59732a6700/688] [fixUacContacts, RegisterDialog.cpp:220] DEBUG:  
min_reg_expire = 3600
 [#7f59732a6700/688] [fixUacContacts, RegisterDialog.cpp:221] DEBUG:  
max_ua_expire = 60
 [#7f59732a6700/688] [fixUacContacts, RegisterDialog.cpp:222] DEBUG:  
contact_expires = 60
 [#7f59732a6700/688] [fixUacContacts, RegisterDialog.cpp:223] DEBUG:  
reg_expires = 3552
 [#7f59732a6700/688] [onUpdate, RegisterCache.cpp:183] DEBUG:  update: 
alias='b0ced53df78e9e34';ua_expires=1410488843
 [#7f59732a6700/688] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  
contact: <<sip:[email protected]:9960>;expires=60>
 [#7f59732a6700/688] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI 
port (9960) to int (9960)
 [#7f59732a6700/688] [send_reply, trans_layer.cpp:410] DEBUG:  reply_len = 428
 [#7f59732a6700/688] [is_opt_set, transport.cpp:76] DEBUG:  
trsp_socket::socket_options = 0x2
 [#7f59732a6700/688] [is_opt_set, transport.cpp:76] DEBUG:  
trsp_socket::socket_options = 0x2
 [#7f59732a6700/688] [send_reply, trans_layer.cpp:585] DEBUG:  Sending to 
192.168.131.182:9960 <SIP/2.0 200 OK
To: phone03<sip:[email protected]...>
 [#7f59732a6700/688] [send, udp_trsp.cpp:259] DEBUG: send  msg to 
192.168.131.182:9960
--++--
SIP/2.0 200 OK
To: phone03<sip:[email protected]>;tag=43573B6B-541259CF000A7EC1-732A6700
From: phone03<sip:[email protected]>;tag=7750b76d
Via: SIP/2.0/UDP 
192.168.131.182:9960;branch=z9hG4bK-d87543-682559995-1--d87543-;rport=9960
Call-ID: 52403c6d4d2a8f21
CSeq: 2 REGISTER
Contact: <sip:[email protected]:9960;transport=udp>;expires=60
Server: Sip Express Media Server (1.6.0 (x86_64/linux))
Content-Length: 0

--++--
 [#7f59732a6700/688] [update_uas_reply, trans_layer.cpp:2126] DEBUG:  
update_uas_reply(t=0x88ed10)
 [#7f59732a6700/688] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type 
J at time=5753 (repeated=0)
 [#7f59732a6700/688] [finalize, SBCSimpleRelay.cpp:222] DEBUG:  finalize(): 
tag=476C5CA2-541259CF000A7C8D-732A6700
 [#7f59732a6700/688] [~SimpleRelayDialog, SBCSimpleRelay.cpp:72] DEBUG:  
~SimpleRelayDialog: local_tag = 476C5CA2-541259CF000A7C8D-732A6700
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:733] DEBUG:  callid = 
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:734] DEBUG:  local_tag = 
476C5CA2-541259CF000A7C8D-732A6700
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:735] DEBUG:  uac_trans.size() 
= 0
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:743] DEBUG:  uas_trans.size() 
= 0
 [#7f59732a6700/688] [finalize, SBCSimpleRelay.cpp:222] DEBUG:  finalize(): tag=
 [#7f59732a6700/688] [~SimpleRelayDialog, SBCSimpleRelay.cpp:72] DEBUG:  
~SimpleRelayDialog: local_tag = 
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:733] DEBUG:  callid = 
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:734] DEBUG:  local_tag = 
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:735] DEBUG:  uac_trans.size() 
= 0
 [#7f59732a6700/688] [dump, AmBasicSipDialog.cpp:743] DEBUG:  uas_trans.size() 
= 0
 [#7f59732a6700/688] [handle_sip_request, SipCtrlInterface.cpp:788] DEBUG:  ^^ 
M [52403c6d4d2a8f21|] Ru SIP request REGISTER handled ^^
 [#7f59733a7700/687] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59733a7700/687] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
"
 [#7f59730a4700/690] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via 
UDP from 192.168.131.182:9960 vv
--++--

--++--
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1572] DEBUG:  parse_sip_msg 
returned -7
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1578] DEBUG:  parsing 
error: Could not parse first line
 [#7f59730a4700/690] [received_msg, trans_layer.cpp:1580] DEBUG:  Message was: "
_______________________________________________
Sems mailing list
[email protected]
http://lists.iptel.org/mailman/listinfo/sems

Reply via email to