[Freeswitch-users] Talking with Google

20 Apr 2007

      Hi,

i startet yesterday with FreeSwitch with the main purpose to get a
connection to GoogleTalk.

After working through compilation (checked out the svn version) and
first configuration i started freeswitch and the connection to google
talk seemed just fine (registration with google talk). I have an SIP
phone subscribed with FreeSwitch and have an extension configured that
calls my GoogleTalk User. If i dial on the SIP phone the GoogleTalk
Client indicates an incoming call but there is no audio then i pick up.
Strangely i can see no RTP packets sending from Freeswitch (here i mean
from freeswitch to the google talk client, to the sip phone rtp is no
problem).

Below you will see all the config file and and extensive console log,
but i would like to point your interest to one jabber message that i
have found:

2007-04-19 20:07:23 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="error" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="301"
from="evision.testi@googlemail.com/Talk.v923ADBA
44D">
  <session type="transport-info" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.
google.com/session">
    <transport xmlns="http://www.google.com/transport/p2p">
      <info_element name="caller_id_number" value="wolf"
xmlns="http://www.freeswitch.org/jie"></info_element>
      <info_element name="caller_id_name" value="wolf"
xmlns="http://www.freeswitch.org/jie"></info_element>
      <candidate name="rtp" address="194.77.215.35" port="16384"
username="3zTuGpEcqRQxlmHS" password="EAwqy9hI6hvM9JyF"
 preference="1.0" protocol="udp" type="local" network="0"
generation="0"></candidate>
    </transport>
  </session>
  <error code="501" type="cancel">
    <feature-not-implemented
xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented>
  </error>
</iq>

I think that my connection will not work until i get rid of this
message. Does anyone know the cause of such a message ?

I have worked through the mailinglist archive for problems with google
talk, but i have not found a case similar to mine.

If i have forgotten any useful information, just mail me what is missing
and i will be happy to provide it.

Here is my setup:

freeswitch --> internet --> nat --> google talk

Here the config files:

-----------------freeswitch.xml-------------------------
<?xml version="1.0"?>
<document type="freeswitch/xml">

  <!--#set "domain=gue1"-->
  <!--#set "subdomain=gue1.tech-on-air.com"-->
  <!--#set "default_codecs=PCMA"-->
  <!--my domain is $${domain}-->
  <section name="configuration" description="Various Configuration">
    <!--#include "switch.conf.xml"-->
    <!--#include "modules.conf.xml"-->
    <!--#include "spidermonkey.conf.xml"-->
    <!--#include "event_multicast.conf.xml"-->
    <!--#include "event_socket.conf.xml"-->
    <!--#include "iax.conf.xml"-->
    <!--#include "console.conf.xml"-->
    <!--#include "sofia.conf.xml"-->
    <!--#include "syslog.conf.xml"-->
    <!--#include "woomera.conf.xml"-->
    <!--#include "wanpipe.conf.xml"-->
    <!--#include "portaudio.conf.xml"-->
    <!--#include "zeroconf.conf.xml"-->
    <!--#include "xmpp_event.conf.xml"-->
    <!--#include "dialplan_directory.conf.xml"-->
    <!--#include "dingaling.conf.xml"-->
    <!--#include "xml_curl.conf.xml"-->
    <!--#include "xml_rpc.conf.xml"-->
    <!--#include "rss.conf.xml"-->
    <!--#include "conference.conf.xml"-->
    <!--#include "enum.conf.xml"-->
    <!--#include "ivr.conf.xml"-->
    <!--#include "mod_cdr.conf.xml"-->
  </section>

  <section name="dialplan" description="Regex/XML Dialplan">
    <!--#include "default_context.xml"-->
  </section>

  <section name="directory" description="User Directory">
    <!--#include "directory.xml"-->
  </document>
</section>

-------------------sofia------------------------
I have blind registration activated and connection between two sip
clients works.
------------------------------------------------

------------------dingaling-------------------
<configuration name="dingaling.conf" description="XMPP Jingle Endpoint">
  <settings>
    <param name="debug" value="1"/>
    <param name="codec-prefs" value="PCMU@8000"/>
  </settings>

  <profile type="client">
    <param name="name" value="google"/>
    <param name="login" value="tobias.wolf5@googlemail.com/talk"/>
    <param name="password" value="XXXXXXX"/>
    <param name="server" value="talk.google.com:5222"/>
    <param name="dialplan" value="XML"/>
    <param name="message" value="Jingle all the way"/>
    <param name="rtp-ip" value="194.77.215.35"/>
    <param name="auto-login" value="true"/>
    <!-- SASL "plain" or "md5" -->
    <param name="sasl" value="md5"/>
    <!-- Enable TLS or not -->
    <param name="tls" value="true"/>
    <!-- disable to trade async for more calls -->
    <param name="use-rtp-timer" value="true"/>
    <param name="ext-rtp-ip" value="194.77.215.35"/>
    <!-- default extension (if one cannot be determined) -->
    <param name="exten" value="2002"/>
    <!-- VAD choose one -->
    <!-- <param name="vad" value="in"/> -->
    <!-- <param name="vad" value="out"/> -->
    <param name="vad" value="both"/>
  </profile>

</configuration>
-------------------------------------------------------

----------------default_context------------------------
<!-- Valid fields in conditions: -->
<!-- "dialplan, caller_id_name, ani, ani2, caller_id_number, -->
<!-- rdnis, destination_number, uuid, source, context, chan_name" -->

<!-- *NOTE* The special context name 'any' will match any context -->
<context name="default">
  <extension name="toa">
        <condition field="destination_number" expression="^2001$">
          <!-- action application="bridge"
data="sofia/$${domain}/wolf%194.77.215.35"/-->
          <action application="bridge"
data="dingaling/google/evision.testi@googlemail.com"/>
        </condition>
  </extension>

  <extension name="toa1">
        <condition field="destination_number" expression="^2002$">
          <action application="playback"
data="/var/lib/asterisk/sounds/en/demo-congrats.gsm"/>
        </condition>
  </extension>
</context>
---------------------------------------------------------------------

Following a complete console log. Here i have done the following:
1. Call from SIP to GoogleTalk
2. Call from GoogleTalk to Freeswitch (demo-congrats should be streamed)

-------------------------console.log--------------------------------
2007-04-19 20:07:06 [CONSOLE] switch_core.c:572
switch_core_init_and_modload() Bringing up environment.
2007-04-19 20:07:06 [CONSOLE] switch_core.c:573
switch_core_init_and_modload() Loading Modules.
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_c
onsole]
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_e
num]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:178
switch_loadable_module_process() Adding Dialplan 'enum'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'enum'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'enum'
2007-04-19 20:07:06 [NOTICE] switch_scheduler.c:129
switch_scheduler_task_thread() Starting task thread
2007-04-19 20:07:06 [DEBUG] xmlcdr.cpp:107 connect() XmlCDR::connect() -
Loading configuration file.
2007-04-19 20:07:06 [DEBUG] csvcdr.cpp:105 connect() CsvCDR::connect() -
Loading configuration file.
File size limit from config file is 26214400 byte(s).
2007-04-19 20:07:06 [DEBUG] csvcdr.cpp:186 connect() CsvCDR activated,
log rotation will occur at or after 25 MB
2007-04-19 20:07:06 [DEBUG] pddcdr.cpp:105 connect() PddCDR::connect() -
Loading configuration file.
2007-04-19 20:07:06 [ERR] pddcdr.cpp:162 connect() PddCDR::connect():
You did not specify the minimum parameters for usi
ng this module.  You must specify at least a path to have the records
logged to.
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_c
dr - CDR Engine]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'modcdr_r
eload'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'modcdr_q
ueue_pause'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'modcdr_q
ueue_resume'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'modcdr_s
how_active'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'modcdr_s
how_available'
2007-04-19 20:07:06 [ERR] switch_loadable_module.c:437
switch_loadable_module_load_file() Error Loading module /usr/loca
l/freeswitch/mod/mod_xmpp_event.so
**/usr/local/freeswitch/mod/mod_xmpp_event.so: undefined symbol:
iks_stream_new**
2007-04-19 20:07:06 [DEBUG] mod_dingaling.c:1892 init_profile() Started
Thread for tobias.wolf5@googlemail.com/talk@XML
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_d
ingaling]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:128
switch_loadable_module_process() Adding Endpoint 'dingaling'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'dl_login
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'dl_logou
t'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'dl_pres'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'dl_debug
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:329
switch_loadable_module_process() Adding Chat interface 'jingle
'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_i
ax]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:128
switch_loadable_module_process() Adding Endpoint 'iax'
2007-04-19 20:07:06 [NOTICE] sofia.c:793 config_sofia() Started Profile
gue1 [sofia_reg_gue1]
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_s
ofia]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:128
switch_loadable_module_process() Adding Endpoint 'sofia'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:329
switch_loadable_module_process() Adding Chat interface 'sip'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:369
switch_loadable_module_process() Adding Management interface '
mod_sofia' OID[.1.3.6.1.4.1.27880.1]
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_b
ridgecall]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'bridge'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_c
ommands]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'originat
e'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'killchan
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'reloadxm
l'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'load'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'transfer
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'pause'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'show'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'status'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'uuid_bri
dge'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'session_
record'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'broadcas
t'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'hold'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'media'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'fsctl'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'help'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'version'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'sched_ha
ngup'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'sched_br
oadcast'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'sched_tr
ansfer'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'sched_ap
i'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'sched_de
l'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'xml_wrap
'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_c
onference]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'conferenc
e'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'conferen
ce'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:329
switch_loadable_module_process() Adding Chat interface 'conf'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_d
ptools]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'privacy'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'transfer'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'sleep'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'strftime'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'phrase'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'eval'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'pre_answe
r'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'answer'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'hangup'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'log'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'info'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'export'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'set'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'unset'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'ring_read
y'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'detect_sp
eech'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'ivr'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'redirect'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'queue_dtm
f'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'sched_han
gup'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'sched_bro
adcast'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'sched_tra
nsfer'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'presence
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'strftime
'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'chat'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:234
switch_loadable_module_process() Adding API Function 'strepoch
'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_e
cho]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'echo'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_p
layback]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'playback'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'stop_reco
rd_session'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'record_se
ssion'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'record'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:214
switch_loadable_module_process() Adding Application 'speak'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_d
ialplan_xml]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:178
switch_loadable_module_process() Adding Dialplan 'XML'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_g
711]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMU' (g711 ula
w) 8000hz 10ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMU' (g711 ula
w) 8000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMU' (g711 ula
w) 8000hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMU' (g711 ula
w) 8000hz 60ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMU' (g711 ula
w) 8000hz 120ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMA' (g711 ala
w) 8000hz 10ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMA' (g711 ala
w) 8000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMA' (g711 ala
w) 8000hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMA' (g711 ala
w) 8000hz 60ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'PCMA' (g711 ala
w) 8000hz 120ms
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_g
sm]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'GSM' (gsm) 8000
hz 20ms
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_i
lbc]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 80
00hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 80
00hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'iLBC102' (ilbc)
 8000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'iLBC' (ilbc) 80
00hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'iLBC20ms' (ilbc
) 8000hz 20ms
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_l
16]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 10ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 60ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 120ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 16000hz 10ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 16000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 16000hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 16000hz 60ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 8000hz 120ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 22050hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 32000hz 10ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 32000hz 20ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 32000hz 30ms
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:154
switch_loadable_module_process() Adding Codec 'L16' (raw signe
d linear (16 bit)) 32000hz 60ms
2007-04-19 20:07:06 [INFO] mod_sndfile.c:297 setup_formats()
LibSndFile Version : libsndfile-1.0.17 Supported Formats
================================================================================
AIFF (Apple/SGI)  (extension "aiff")
AU (Sun/NeXT)  (extension "au")
AVR (Audio Visual Research)  (extension "avr")
CAF (Apple Core Audio File)  (extension "caf")
HTK (HMM Tool Kit)  (extension "htk")
IFF (Amiga IFF/SVX8/SV16)  (extension "iff")
MAT4 (GNU Octave 2.0 / Matlab 4.2)  (extension "mat")
MAT5 (GNU Octave 2.1 / Matlab 5.0)  (extension "mat")
PAF (Ensoniq PARIS)  (extension "paf")
PVF (Portable Voice Format)  (extension "pvf")
RAW (header-less)  (extension "raw")
SD2 (Sound Designer II)  (extension "sd2")
SDS (Midi Sample Dump Standard)  (extension "sds")
SF (Berkeley/IRCAM/CARL)  (extension "sf")
VOC (Creative Labs)  (extension "voc")
W64 (SoundFoundry WAVE 64)  (extension "w64")
WAV (Microsoft)  (extension "wav")
WAV (NIST Sphere)  (extension "wav")
WAVEX (Microsoft)  (extension "wav")
XI (FastTracker 2)  (extension "xi")
================================================================================
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_s
ndfile]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'aiff'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'au'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'avr'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'caf'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'htk'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'iff'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'mat'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'paf'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'pvf'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'raw'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'sd2'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'sds'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'sf'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'voc'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'w64'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'wav'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'xi'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'r8'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'r16'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'r24'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'r32'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'gsm'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'ul'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'al'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_n
ative_file]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'PCMA'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'L16'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'PCMU'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'GSM'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'iLBC'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'iLBC20ms'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:256
switch_loadable_module_process() Adding File Format 'iLBC102'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_s
ofttimer]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:196
switch_loadable_module_process() Adding Timer 'soft'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:453
switch_loadable_module_load_file() Successfully Loaded [mod_s
ay_en]
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:347
switch_loadable_module_process() Adding Say interface 'en'
2007-04-19 20:07:06 [NOTICE] switch_loadable_module.c:347
switch_loadable_module_process() Adding Say interface 'es'
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:674
switch_loadable_module_init() open of post_load_modules.conf
failed
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:110
switch_loadable_module_runtime() Starting runtime thread for
mod_iax
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:110
switch_loadable_module_runtime() Starting runtime thread for
mod_cdr - CDR Engine
2007-04-19 20:07:06 [CONSOLE] switch_loadable_module.c:110
switch_loadable_module_runtime() Starting runtime thread for
mod_softtimer
2007-04-19 20:07:06 [CONSOLE] switch_core.c:585
switch_core_init_and_modload()
FreeSWITCH Version 1.0.exportiert  Started.
Crash Protection [DISABLED]
Max Sessions[1000]

freeswitch@gue1> 2007-04-19 20:07:06 [INFO] mod_iax.c:991
switch_module_runtime() IAX Ready Port 4569
2007-04-19 20:07:06 [DEBUG] mod_cdr.cpp:148 switch_module_runtime()
mod_cdr made it to runtime.  Wee!
2007-04-19 20:07:06 [NOTICE] libdingaling.c:1255 on_log() SEND:
-------------------------------------------------------------------------------
<stream:stream xmlns:stream="http://etherx.jabber.org/streams"
xmlns="jabber:client" to="googlemail.com" version="1.0"><
/stream:stream>

2007-04-19 20:07:06 [INFO] libdingaling.c:1253 on_log() RECV:
-------------------------------------------------------------------------------
<stream:stream from="googlemail.com" id="237766E9964EC423" version="1.0"
xmlns:stream="http://etherx.jabber.org/streams"
 xmlns="jabber:client"></stream:stream>

2007-04-19 20:07:06 [NOTICE] libdingaling.c:1255 on_log() SEND:
-------------------------------------------------------------------------------
<starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls"></starttls>

2007-04-19 20:07:06 [INFO] libdingaling.c:1253 on_log() RECV:
-------------------------------------------------------------------------------
<stream:features>
  <starttls xmlns="urn:ietf:params:xml:ns:xmpp-tls">
    <required></required>
  </starttls>
  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">
    <mechanism>X-GOOGLE-TOKEN</mechanism>
  </mechanisms>
</stream:features>

2007-04-19 20:07:06 [INFO] libdingaling.c:1253 on_log() RECV:
-------------------------------------------------------------------------------
<proceed xmlns="urn:ietf:params:xml:ns:xmpp-tls"></proceed>

2007-04-19 20:07:06 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<stream:stream xmlns:stream="http://etherx.jabber.org/streams"
xmlns="jabber:client" to="googlemail.com" version="1.0"><
/stream:stream>

2007-04-19 20:07:06 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<stream:stream from="googlemail.com" id="D57988F1FAEC21A7" version="1.0"
xmlns:stream="http://etherx.jabber.org/streams"
 xmlns="jabber:client"></stream:stream>

2007-04-19 20:07:06 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<stream:features>
  <mechanisms xmlns="urn:ietf:params:xml:ns:xmpp-sasl">
    <mechanism>PLAIN</mechanism>
    <mechanism>X-GOOGLE-TOKEN</mechanism>
  </mechanisms>
</stream:features>

2007-04-19 20:07:06 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN"
encoding="UTF-8">AHRvYmlhcy53b2xmNQBnMDBnbGVNYTFM</auth
...
2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"></success>

2007-04-19 20:07:07 [DEBUG] libdingaling.c:1181 on_stream() XMPP server
connected

2007-04-19 20:07:07 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<stream:stream xmlns:stream="http://etherx.jabber.org/streams"
xmlns="jabber:client" to="googlemail.com" version="1.0"><
/stream:stream>

2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<stream:stream from="googlemail.com" id="E1285B654309DFCC" version="1.0"
xmlns:stream="http://etherx.jabber.org/streams"
 xmlns="jabber:client"></stream:stream>

2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<stream:features>
  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"></bind>
  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"></session>
</stream:features>

2007-04-19 20:07:07 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="set">
  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind">
    <resource>talk</resource>
  </bind>
</iq>

2007-04-19 20:07:07 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="set" id="auth">
  <session xmlns="urn:ietf:params:xml:ns:xmpp-session"></session>
</iq>

2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result">
  <bind xmlns="urn:ietf:params:xml:ns:xmpp-bind">
    <jid>tobias.wolf5@googlemail.com/talk23EB8C69</jid>
  </bind>
</iq>

2007-04-19 20:07:07 [DEBUG] libdingaling.c:1193 on_stream() XMPP
authenticated

2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result" id="auth"></iq>

2007-04-19 20:07:07 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<presence>
  <status>Jingle all the way</status>
  <c node="http://www.freeswitch.org/xmpp/client/caps" ver="1.0.0.1"
ext="sidebar voice-v1" client="libdingaling" xmlns=
"http://jabber.org/protocol/caps"></c>
</presence>

2007-04-19 20:07:07 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<presence from="evision.testi@googlemail.com/Talk.v923ADBA44D"
to="tobias.wolf5@googlemail.com/talk23EB8C69">
  <show>away</show>
  <priority>0</priority>
  <c node="http://www.google.com/xmpp/client/caps" ver="1.0.0.92"
ext="voice-v1" xmlns="http://jabber.org/protocol/caps"
...
</c>
  <x stamp="20070419T18:01:46" xmlns="jabber:x:delay"></x>
  <status></status>
  <x xmlns="vcard-temp:x:update">
    <photo></photo>
  </x>
</presence>
2007-04-19 20:07:07 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<presence type="subscribed" to="evision.testi@googlemail.com">
  <status>Ding A Ling....</status>
</presence>

2007-04-19 20:07:13 [NOTICE] switch_channel.c:347
switch_channel_set_name() New Chan sofia/gue1/wolf@194.77.215.35:5060
[cc82be9e-eea0-11db-8658-1b712236e848]
2007-04-19 20:07:13 [DEBUG] sofia.c:849 sofia_handle_sip_i_state()
Channel sofia/gue1/wolf@194.77.215.35:5060 entering s
tate [received]
2007-04-19 20:07:13 [DEBUG] sofia.c:853 sofia_handle_sip_i_state()
Remote SDP:
v=0
o=root 11980311 11980311 IN IP4 10.3.4.91
s=call
c=IN IP4 10.3.4.91
t=0 0
m=audio 51992 RTP/AVP 0 8 9 2 3 18 4 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:9 g722/8000
a=rtpmap:2 g726-32/8000
a=rtpmap:3 gsm/8000
a=rtpmap:18 g729/8000
a=rtpmap:4 g723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=crypto:1 AES_CM_128_HMAC_SHA1_32
inline:bvuXaN0nVYQ2rSkp0y3Hgs9FaUTlKhna548R9Tkn
a=ptime:20
a=encryption:optional

2007-04-19 20:07:13 [DEBUG] sofia_glue.c:899 sofia_glue_negotiate_sdp()
Codec Compare [pcmu:0]/[PCMA:8]
2007-04-19 20:07:13 [DEBUG] sofia_glue.c:899 sofia_glue_negotiate_sdp()
Codec Compare [pcma:8]/[PCMA:8]
2007-04-19 20:07:13 [INFO] sofia_glue.c:615 sofia_glue_tech_set_codec()
Set Codec sofia/gue1/wolf@194.77.215.35:5060 pcm
a/8000 20 ms
2007-04-19 20:07:13 [DEBUG] sofia_glue.c:875 sofia_glue_negotiate_sdp()
Set 2833 dtmf payload to 101
2007-04-19 20:07:13 [DEBUG] sofia.c:960 sofia_handle_sip_i_state()
sofia/gue1/wolf@194.77.215.35:5060 State Change CS_NE
W -> CS_INIT
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:387
switch_core_session_run() (sofia/gue1/wolf@194.77.215.35:506
0) State INIT
2007-04-19 20:07:13 [DEBUG] mod_sofia.c:86 sofia_on_init() SOFIA INIT
2007-04-19 20:07:13 [DEBUG] mod_sofia.c:100 sofia_on_init()
sofia/gue1/wolf@194.77.215.35:5060 State Change CS_INIT -> C
S_RING
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:422
switch_core_session_run() (sofia/gue1/wolf@194.77.215.35:506
0) State RING
2007-04-19 20:07:13 [DEBUG] mod_sofia.c:115 sofia_on_ring() SOFIA RING
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:56
switch_core_standard_on_ring() Standard RING sofia/gue1/wolf@
194.77.215.35:5060
2007-04-19 20:07:13 [INFO] mod_dialplan_xml.c:313 dialplan_hunt()
Processing wolf->2001!
2007-04-19 20:07:13 [DEBUG] mod_dialplan_xml.c:117 parse_exten() test
conditions destination_number(2001) =~ /^556$/
2007-04-19 20:07:13 [DEBUG] mod_dialplan_xml.c:119 parse_exten() Regex
mismatch
2007-04-19 20:07:13 [DEBUG] mod_dialplan_xml.c:117 parse_exten() test
conditions destination_number(2001) =~ /^2001$/
2007-04-19 20:07:13 [DEBUG] mod_dialplan_xml.c:369 dialplan_hunt()
sofia/gue1/wolf@194.77.215.35:5060 State Change CS_RI
NG -> CS_EXECUTE
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:457
switch_core_session_run() (sofia/gue1/wolf@194.77.215.35:506
0) State EXECUTE
2007-04-19 20:07:13 [DEBUG] mod_sofia.c:132 sofia_on_execute() SOFIA EXECUTE
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:114
switch_core_standard_on_execute() Standard EXECUTE
2007-04-19 20:07:13 [NOTICE] switch_core_state_machine.c:124
switch_core_standard_on_execute() Execute bridge(dingaling/
google/evision.testi@googlemail.com)
2007-04-19 20:07:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<presence type="subscribe" to="evision.testi@googlemail.com"
from="tobias.wolf5@googlemail.com/talk23EB8C69">
  <status>Call Me!</status>
</presence>

2007-04-19 20:07:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<presence xmlns="jabber:client" type="probe"
to="evision.testi@googlemail.com" from="tobias.wolf5@googlemail.com/talk23E
B8C69"></presence>

2007-04-19 20:07:13 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<presence from="evision.testi@googlemail.com/Talk.v923ADBA44D"
to="tobias.wolf5@googlemail.com">
  <show>away</show>
  <priority>0</priority>
  <c node="http://www.google.com/xmpp/client/caps" ver="1.0.0.92"
ext="voice-v1" xmlns="http://jabber.org/protocol/caps"
...
</c>
  <x stamp="20070419T18:01:46" xmlns="jabber:x:delay"></x>
  <status></status>
  <x xmlns="vcard-temp:x:update">
    <photo></photo>
  </x>
</presence>
2007-04-19 20:07:13 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<presence from="evision.testi@googlemail.com/Talk.v923ADBA44D"
to="tobias.wolf5@googlemail.com/talk23EB8C69">
  <show>away</show>
  <priority>0</priority>
  <c node="http://www.google.com/xmpp/client/caps" ver="1.0.0.92"
ext="voice-v1" xmlns="http://jabber.org/protocol/caps"
...
</c>
  <x stamp="20070419T18:01:46" xmlns="jabber:x:delay"></x>
  <status></status>
  <x xmlns="vcard-temp:x:update">
    <photo></photo>
  </x>
</presence>
2007-04-19 20:07:13 [NOTICE] switch_channel.c:347
switch_channel_set_name() New Chan DingaLing/google/evision.testi@goog
lemail.com [cc83cf50-eea0-11db-8658-1b712236e848]
2007-04-19 20:07:13 [DEBUG] libdingaling.c:337 ldl_session_create()
Created Session 8376255424

2007-04-19 20:07:13 [DEBUG] mod_dingaling.c:1806
channel_outgoing_channel() DingaLing/google/evision.testi@googlemail.co
m State Change CS_NEW -> CS_INIT
2007-04-19 20:07:13 [DEBUG] switch_core_state_machine.c:387
switch_core_session_run() (DingaLing/google/evision.testi@go
oglemail.com) State INIT
2007-04-19 20:07:13 [NOTICE] mod_dingaling.c:1064 negotiate_media()
Ring-Ready DingaLing/google/evision.testi@googlemail
.com!
2007-04-19 20:07:13 [DEBUG] mod_dingaling.c:1013 do_describe() Don't
have my codec yet here's one
2007-04-19 20:07:13 [DEBUG] mod_dingaling.c:1033 do_describe() Send
Describe [PCMU@8000]
2007-04-19 20:07:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<message to="evision.testi@googlemail.com" type="chat"
from="tobias.wolf5@googlemail.com/talk23EB8C69" subject="">
  <body>Incoming Call From wolf wolf
  </body>
</message>

2007-04-19 20:07:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:13 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 300 (2 left)

2007-04-19 20:07:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="300">
  <session xmlns="http://www.google.com/session" type="initiate"
id="8376255424" initiator="tobias.wolf5@googlemail.com/
talk23EB8C69">
    <description xmlns="http://www.google.com/session/phone" xml:lang="en">
      <payload-type xmlns="http://www.google.com/session/phone" id="0"
name="PCMU" clockrate="8000" bitrate="64000"></pa
yload-type>
    </description>
    <transport xmlns="http://www.google.com/transport/p2p"></transport>
  </session>
</iq>

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:14 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="300"
from="evision.testi@googlemail.com/Talk.v923ADB
A44D"></iq>

2007-04-19 20:07:14 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 300

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:14 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 300

2007-04-19 20:07:14 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="72"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1520"
username="P01W5nFkQo8ahY/S" password="y5gr0KqSzz5AQkQE" prefer
ence="1" protocol="udp" type="local" network="0" generation="0"></candidate>
  </session>
</iq>

2007-04-19 20:07:14 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:14 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"72">
  <query></query>
</iq>

2007-04-19 20:07:14 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="73"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1521"
username="x6lD3i/9wADSOSLE" password="ZQ5+gXLW1HvPaqYi" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="0"></candidate>
  </session>
</iq>

2007-04-19 20:07:14 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:14 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"73">
  <query></query>
</iq>

2007-04-19 20:07:23 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<presence from="evision.testi@googlemail.com/Talk.v923ADBA44D"
to="tobias.wolf5@googlemail.com">
  <priority>0</priority>
  <c node="http://www.google.com/xmpp/client/caps" ver="1.0.0.92"
ext="voice-v1" xmlns="http://jabber.org/protocol/caps"
...
</c>
  <x stamp="20070419T18:07:20" xmlns="jabber:x:delay"></x>
  <status></status>
  <x xmlns="vcard-temp:x:update">
    <photo></photo>
  </x>
</presence>
2007-04-19 20:07:23 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="76"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="accept" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69"
xmlns="http://www.google.c
om/session">
    <description xmlns="http://www.google.com/session/phone">
      <payload-type id="0" name="PCMU"></payload-type>
    </description>
  </session>
</iq>

2007-04-19 20:07:23 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:23 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [PCMU] id='0'

2007-04-19 20:07:23 [DEBUG] mod_dingaling.c:2579 handle_signalling()
using Existing session for 8376255424
2007-04-19 20:07:23 [DEBUG] mod_dingaling.c:965 do_candidates() Send
Candidate 194.77.215.35:16384 [3zTuGpEcqRQxlmHS]
2007-04-19 20:07:23 [DEBUG] mod_dingaling.c:2781 handle_signalling()
Already decided on a codec
2007-04-19 20:07:23 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"76">
  <query></query>
</iq>

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 301 (2 left)

2007-04-19 20:07:23 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="301">
  <session xmlns="http://www.google.com/session" type="transport-info"
id="8376255424" initiator="tobias.wolf5@googlemai
l.com/talk23EB8C69">
    <transport xmlns="http://www.google.com/transport/p2p">
      <info_element xmlns="http://www.freeswitch.org/jie"
name="caller_id_number" value="wolf"></info_element>
      <info_element xmlns="http://www.freeswitch.org/jie"
name="caller_id_name" value="wolf"></info_element>
      <candidate name="rtp" address="194.77.215.35" port="16384"
username="3zTuGpEcqRQxlmHS" password="EAwqy9hI6hvM9JyF"
 preference="1.0" protocol="udp" type="local" network="0"
generation="0"></candidate>
    </transport>
  </session>
</iq>

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="error" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="301"
from="evision.testi@googlemail.com/Talk.v923ADBA
44D">
  <session type="transport-info" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.
google.com/session">
    <transport xmlns="http://www.google.com/transport/p2p">
      <info_element name="caller_id_number" value="wolf"
xmlns="http://www.freeswitch.org/jie"></info_element>
      <info_element name="caller_id_name" value="wolf"
xmlns="http://www.freeswitch.org/jie"></info_element>
      <candidate name="rtp" address="194.77.215.35" port="16384"
username="3zTuGpEcqRQxlmHS" password="EAwqy9hI6hvM9JyF"
 preference="1.0" protocol="udp" type="local" network="0"
generation="0"></candidate>
    </transport>
  </session>
  <error code="501" type="cancel">
    <feature-not-implemented
xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented>
  </error>
</iq>

2007-04-19 20:07:23 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 301

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:07:23 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 301

2007-04-19 20:07:34 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="77"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1523"
username="FN7TVdONHmUZJy86" password="g/gvhec0kZfM2YC+" prefer
ence="1" protocol="udp" type="local" network="0" generation="1"></candidate>
  </session>
</iq>

2007-04-19 20:07:34 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:34 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"77">
  <query></query>
</iq>

2007-04-19 20:07:34 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="78"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1524"
username="OGZeCvIN7sZDnDLM" password="tJsRmJqZKqmPoLKG" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="1"></candidate>
  </session>
</iq>

2007-04-19 20:07:34 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:34 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"78">
  <query></query>
</iq>

2007-04-19 20:07:59 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="79"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1527"
username="sgpWmfpgpzUjWK3E" password="OSSYimonKsSva9Ys" prefer
ence="1" protocol="udp" type="local" network="0" generation="2"></candidate>
  </session>
</iq>

2007-04-19 20:07:59 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:59 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"79">
  <query></query>
</iq>

2007-04-19 20:07:59 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="80"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.goog
le.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1528"
username="zHCX2wst/8ZxPxJ0" password="J5OUiHbDT+eKQOQN" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="2"></candidate>
  </session>
</iq>

2007-04-19 20:07:59 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:07:59 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"80">
  <query></query>
</iq>

2007-04-19 20:08:13 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="81"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="terminate" id="8376255424"
initiator="tobias.wolf5@googlemail.com/talk23EB8C69" xmlns="http://www.googl
e.com/session"></session>
</iq>

2007-04-19 20:08:13 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 8376255424

2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:2579 handle_signalling()
using Existing session for 8376255424
2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:2935 handle_signalling()
hungup DingaLing/google/evision.testi@googlemail.co
m
2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:682 terminate_session()
Terminate called from line 2936 state=CS_INIT
2007-04-19 20:08:13 [NOTICE] mod_dingaling.c:697 terminate_session()
Hangup DingaLing/google/evision.testi@googlemail.co
m [CS_INIT] [NORMAL_CLEARING]
2007-04-19 20:08:13 [INFO] switch_channel.c:1026
switch_channel_perform_hangup() Kill DingaLing/google/evision.testi@goo
glemail.com [KILL]
2007-04-19 20:08:13 [DEBUG] libdingaling.c:282 ldl_session_destroy()
Destroyed Session 8376255424

2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:1286 channel_kill_channel()
DingaLing/google/evision.testi@googlemail.com CH
ANNEL KILL
2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:682 terminate_session()
Terminate called from line 1129 state=CS_HANGUP
2007-04-19 20:08:13 [DEBUG] switch_core_state_machine.c:351
switch_core_session_run() (DingaLing/google/evision.testi@go
oglemail.com) State HANGUP
2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:1241 channel_on_hangup()
DingaLing/google/evision.testi@googlemail.com CHANN
EL HANGUP
2007-04-19 20:08:13 [INFO] cdrcontainer.cpp:229 add_cdr() Adding a new
logger object to the queue.
2007-04-19 20:08:13 [DEBUG] switch_core_state_machine.c:45
switch_core_standard_on_hangup() Standard HANGUP DingaLing/go
ogle/evision.testi@googlemail.com, cause: NORMAL_CLEARING
2007-04-19 20:08:13 [DEBUG] switch_core_session.c:696
switch_core_session_thread() Session 2 (DingaLing/google/evision.t
esti@googlemail.com) Locked, Waiting on external entities
2007-04-19 20:08:13 [DEBUG] switch_ivr_originate.c:879
switch_ivr_originate() Originate Resulted in Error Cause: 16 [NOR
MAL_CLEARING]
2007-04-19 20:08:13 [INFO] mod_bridgecall.c:76 audio_bridge_function()
Originate Failed.  Cause: NORMAL_CLEARING
2007-04-19 20:08:13 [NOTICE] mod_bridgecall.c:80 audio_bridge_function()
Hangup sofia/gue1/wolf@194.77.215.35:5060 [CS_E
XECUTE] [NORMAL_CLEARING]
2007-04-19 20:08:13 [INFO] switch_channel.c:1026
switch_channel_perform_hangup() Kill sofia/gue1/wolf@194.77.215.35:5060
 [KILL]
2007-04-19 20:08:13 [DEBUG] switch_core_state_machine.c:351
switch_core_session_run() (sofia/gue1/wolf@194.77.215.35:506
0) State HANGUP
2007-04-19 20:08:13 [DEBUG] mod_sofia.c:212 sofia_on_hangup() Channel
sofia/gue1/wolf@194.77.215.35:5060 hanging up, cau
se: NORMAL_CLEARING
2007-04-19 20:08:13 [DEBUG] mod_sofia.c:232 sofia_on_hangup() Responding
to INVITE with: 480
2007-04-19 20:08:13 [INFO] cdrcontainer.cpp:229 add_cdr() Adding a new
logger object to the queue.
2007-04-19 20:08:13 [DEBUG] switch_core_state_machine.c:45
switch_core_standard_on_hangup() Standard HANGUP sofia/gue1/w
olf@194.77.215.35:5060, cause: NORMAL_CLEARING
2007-04-19 20:08:13 [DEBUG] switch_core_session.c:696
switch_core_session_thread() Session 1 (sofia/gue1/wolf@194.77.215
.35:5060) Locked, Waiting on external entities
2007-04-19 20:08:13 [INFO] switch_core_session.c:702
switch_core_session_thread() Session 1 (sofia/gue1/wolf@194.77.215.
35:5060) Ended
2007-04-19 20:08:13 [NOTICE] switch_core_session.c:654
switch_core_session_destroy() Close Channel sofia/gue1/wolf@194.7
7.215.35:5060
2007-04-19 20:08:13 [DEBUG] sofia.c:75 sofia_event_callback() event
[nua_i_state] status [480][Call state] session: n/a
2007-04-19 20:08:13 [DEBUG] mod_dingaling.c:2937 handle_signalling() End
Call
2007-04-19 20:08:13 [INFO] switch_core_session.c:702
switch_core_session_thread() Session 2 (DingaLing/google/evision.te
sti@googlemail.com) Ended
2007-04-19 20:08:13 [NOTICE] switch_core_session.c:654
switch_core_session_destroy() Close Channel DingaLing/google/evis
ion.testi@googlemail.com
2007-04-19 20:08:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"81">
  <query></query>
</iq>

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 302 (2 left)

2007-04-19 20:08:13 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="302">
  <session xmlns="http://www.google.com/session" type="terminate"
id="8376255424" initiator="tobias.wolf5@googlemail.com
/talk23EB8C69"></session>
</iq>

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:13 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="302"
from="evision.testi@googlemail.com/Talk.v923ADB
A44D"></iq>

2007-04-19 20:08:13 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 302

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:13 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 302

2007-04-19 20:08:25 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="82"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="initiate" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D"
xmlns="http://www.g
oogle.com/session">
    <description xmlns="http://www.google.com/session/phone">
      <payload-type id="103" name="ISAC"></payload-type>
      <payload-type id="97" name="IPCMWB"></payload-type>
      <payload-type id="4" name="G723"></payload-type>
      <payload-type id="100" name="EG711U"></payload-type>
      <payload-type id="101" name="EG711A"></payload-type>
      <payload-type id="0" name="PCMU"></payload-type>
      <payload-type id="8" name="PCMA"></payload-type>
      <payload-type id="13" name="CN"></payload-type>
      <payload-type id="102" name="iLBC"></payload-type>
      <payload-type id="117" name="red"></payload-type>
      <payload-type id="106" name="audio/telephone-event"></payload-type>
    </description>
  </session>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:337 ldl_session_create()
Created Session 1372409623

2007-04-19 20:08:25 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [ISAC] id='103'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [IPCMWB] id='97'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [G723] id='4'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [EG711U] id='100'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [EG711A] id='101'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [PCMU] id='0'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [PCMA] id='8'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [CN] id='13'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [iLBC] id='102'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [red] id='117'

2007-04-19 20:08:25 [DEBUG] libdingaling.c:404 parse_session_code() Add
Payload [audio/telephone-event] id='106'

2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2674 handle_signalling()
Creating an identity for 1372409623 evision.testi@g
ooglemail.com/Talk.v923ADBA44D
<evision.testi@googlemail.com/Talk.v923ADBA44D> 2002
2007-04-19 20:08:25 [NOTICE] switch_channel.c:347
switch_channel_set_name() New Chan DingaLing/2002 [f772f646-eea0-11db-
8658-1b712236e848]
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2706 handle_signalling()
Creating a session for 1372409623
2007-04-19 20:08:25 [NOTICE] switch_channel.c:347
switch_channel_set_name() New Chan DingaLing/new [f772f646-eea0-11db-8
658-1b712236e848]
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2710 handle_signalling()
DingaLing/new State Change CS_NEW -> CS_INIT
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2795 handle_signalling() 11
payloads
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload ISAC 103
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare ISAC 103/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload IPCMWB 97
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare IPCMWB 97/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload G723 4
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare G723 4/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload EG711U 100
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare EG711U 100/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload EG711A 101
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare EG711A 101/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2797 handle_signalling()
Available Payload PCMU 0
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2804 handle_signalling()
compare PCMU 0/8000 to PCMU 0/8000
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:2815 handle_signalling()
Choosing Payload index 0 PCMU 0
2007-04-19 20:08:25 [DEBUG] mod_dingaling.c:1033 do_describe() Send
Describe [PCMU@8000]
2007-04-19 20:08:25 [DEBUG] switch_core_state_machine.c:387
switch_core_session_run() (DingaLing/new) State INIT
2007-04-19 20:08:25 [NOTICE] mod_dingaling.c:1064 negotiate_media()
Ring-Ready DingaLing/new!
2007-04-19 20:08:25 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"82">
  <query></query>
</iq>
2007-04-19 20:08:25 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 303 (2 left)

2007-04-19 20:08:25 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="303">
  <session xmlns="http://www.google.com/session" type="accept"
id="1372409623" initiator="evision.testi@googlemail.com/T
alk.v923ADBA44D">
    <description xmlns="http://www.google.com/session/phone" xml:lang="en">
      <payload-type xmlns="http://www.google.com/session/phone" id="0"
name="PCMU" clockrate="8000" bitrate="64000"></pa
yload-type>
    </description>
  </session>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:25 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="84"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1530"
username="DOJ0PeBWM5y0cNq4" password="MFvcD1bmA4ruDYSn" prefer
ence="1" protocol="udp" type="local" network="0" generation="0"></candidate>
  </session>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:08:25 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"84">
  <query></query>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:25 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="85"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1531"
username="JtTd+JO8vUaS+0VW" password="Zw4osUvaWXnwa7ok" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="0"></candidate>
  </session>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623
2007-04-19 20:08:25 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"85">
  <query></query>
</iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:25 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="303"
from="evision.testi@googlemail.com/Talk.v923ADB
A44D"></iq>

2007-04-19 20:08:25 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 303

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:25 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 303

2007-04-19 20:08:35 [DEBUG] mod_dingaling.c:965 do_candidates() Send
Candidate 194.77.215.35:16386 [ko3oZBPdg24Ma3uE]
2007-04-19 20:08:35 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 304 (2 left)

2007-04-19 20:08:35 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="304">
  <session xmlns="http://www.google.com/session" type="transport-info"
id="1372409623" initiator="evision.testi@googlema
il.com/Talk.v923ADBA44D">
    <transport xmlns="http://www.google.com/transport/p2p">
      <candidate name="rtp" address="194.77.215.35" port="16386"
username="ko3oZBPdg24Ma3uE" password="UUclnQtJXYJxZVRt"
 preference="1.0" protocol="udp" type="local" network="0"
generation="0"></candidate>
    </transport>
  </session>
</iq>

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:35 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="error" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="304"
from="evision.testi@googlemail.com/Talk.v923ADBA
44D">
  <session type="transport-info" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http:/
/www.google.com/session">
    <transport xmlns="http://www.google.com/transport/p2p">
      <candidate name="rtp" address="194.77.215.35" port="16386"
username="ko3oZBPdg24Ma3uE" password="UUclnQtJXYJxZVRt"
 preference="1.0" protocol="udp" type="local" network="0"
generation="0"></candidate>
    </transport>
  </session>
  <error code="501" type="cancel">
    <feature-not-implemented
xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"></feature-not-implemented>
  </error>
</iq>

2007-04-19 20:08:35 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 304

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:08:35 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 304

2007-04-19 20:08:45 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="86"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1533"
username="qRa1tWbAj321tbDg" password="7BYKDgi/rK0VJWep" prefer
ence="1" protocol="udp" type="local" network="0" generation="1"></candidate>
  </session>
</iq>

2007-04-19 20:08:45 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:08:45 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"86">
  <query></query>
</iq>

2007-04-19 20:08:45 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="87"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1534"
username="O+Cs+Gjfkf/aDKfT" password="PAsddSEvZNNn3gKO" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="1"></candidate>
  </session>
</iq>

2007-04-19 20:08:45 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:08:45 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"87">
  <query></query>
</iq>

2007-04-19 20:09:10 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="88"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="10.3.4.77" port="1537"
username="tsXRlS+NWNka86BX" password="FeWYGN4ptKrQ3vyT" prefer
ence="1" protocol="udp" type="local" network="0" generation="2"></candidate>
  </session>
</iq>

2007-04-19 20:09:10 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:09:10 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"88">
  <query></query>
</iq>

2007-04-19 20:09:10 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="89"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="candidates" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www
.google.com/session">
    <candidate name="rtp" address="195.162.255.10" port="1538"
username="iPm6mbGWgCOCPcLp" password="CJtIRVe2MfShx7fa" p
reference="0.9" protocol="udp" type="stun" network="0"
generation="2"></candidate>
  </session>
</iq>

2007-04-19 20:09:10 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:09:10 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"89">
  <query></query>
</iq>

2007-04-19 20:09:15 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq to="tobias.wolf5@googlemail.com/talk23EB8C69" type="set" id="90"
from="evision.testi@googlemail.com/Talk.v923ADBA44D
">
  <session type="terminate" id="1372409623"
initiator="evision.testi@googlemail.com/Talk.v923ADBA44D" xmlns="http://www.
google.com/session"></session>
</iq>

2007-04-19 20:09:15 [DEBUG] libdingaling.c:365 parse_session_code()
Message for Session 1372409623

2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:2579 handle_signalling()
using Existing session for 1372409623
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:2935 handle_signalling()
hungup DingaLing/new
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:682 terminate_session()
Terminate called from line 2936 state=CS_INIT
2007-04-19 20:09:15 [NOTICE] mod_dingaling.c:697 terminate_session()
Hangup DingaLing/new [CS_INIT] [NORMAL_CLEARING]
2007-04-19 20:09:15 [INFO] switch_channel.c:1026
switch_channel_perform_hangup() Kill DingaLing/new [KILL]
2007-04-19 20:09:15 [DEBUG] libdingaling.c:282 ldl_session_destroy()
Destroyed Session 1372409623
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:1286 channel_kill_channel()
DingaLing/new CHANNEL KILL
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:2937 handle_signalling() End
Call
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:682 terminate_session()
Terminate called from line 1129 state=CS_HANGUP
2007-04-19 20:09:15 [DEBUG] switch_core_state_machine.c:351
switch_core_session_run() (DingaLing/new) State HANGUP
2007-04-19 20:09:15 [DEBUG] mod_dingaling.c:1241 channel_on_hangup()
DingaLing/new CHANNEL HANGUP
2007-04-19 20:09:15 [INFO] cdrcontainer.cpp:229 add_cdr() Adding a new
logger object to the queue.
2007-04-19 20:09:15 [DEBUG] switch_core_state_machine.c:45
switch_core_standard_on_hangup() Standard HANGUP DingaLing/ne
w, cause: NORMAL_CLEARING
2007-04-19 20:09:15 [DEBUG] switch_core_session.c:696
switch_core_session_thread() Session 3 (DingaLing/new) Locked, Wai
ting on external entities
2007-04-19 20:09:15 [INFO] switch_core_session.c:702
switch_core_session_thread() Session 3 (DingaLing/new) Ended
2007-04-19 20:09:15 [NOTICE] switch_core_session.c:654
switch_core_session_destroy() Close Channel DingaLing/new
2007-04-19 20:09:15 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq type="result" to="evision.testi@googlemail.com/Talk.v923ADBA44D"
from="tobias.wolf5@googlemail.com/talk23EB8C69" id=
"90">
  <query></query>
</iq>

2007-04-19 20:09:15 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:09:15 [DEBUG] libdingaling.c:1354 ldl_flush_queue()
Sending packet 305 (2 left)

2007-04-19 20:09:15 [NOTICE] libdingaling.c:1255 on_log() SecSEND:
-------------------------------------------------------------------------------
<iq xmlns="jabber:client"
from="tobias.wolf5@googlemail.com/talk23EB8C69"
to="evision.testi@googlemail.com/Talk.v923ADBA
44D" type="set" id="305">
  <session xmlns="http://www.google.com/session" type="terminate"
id="1372409623" initiator="evision.testi@googlemail.co
m/Talk.v923ADBA44D"></session>
</iq>

2007-04-19 20:09:15 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:09:15 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:09:15 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:09:16 [INFO] libdingaling.c:1253 on_log() SecRECV:
-------------------------------------------------------------------------------
<iq type="result" to="tobias.wolf5@googlemail.com/talk23EB8C69" id="305"
from="evision.testi@googlemail.com/Talk.v923ADB
A44D"></iq>

2007-04-19 20:09:16 [DEBUG] libdingaling.c:890 cancel_retry() Cancel
packet 305

2007-04-19 20:09:16 [DEBUG] libdingaling.c:1337 ldl_flush_queue()
Processing 1 packets in retry queue

2007-04-19 20:09:16 [DEBUG] libdingaling.c:1362 ldl_flush_queue()
Discarding packet 305

------------------------------------------------------

Everyone who got this far has my greatest respect.

Regards,

Tobias Wolf