Thanks Anthony, Here is the complete console trace you mentioned. Let me first explain the architecture. I wanted to get NAT out of the picture so I installed Freeswitch on a linux box with a public internet IP address. I've masked that public IP address with <FreeswitchServerIP>. My googletalk client is running on 192.168.2.53 behind a NAT firewall from my home. The public IP address for my home is masked with the text <MyExternalIPHome>. So during this call Googletalk should talk with freeswitch. Freeswitch should choose to communicate back on <MyExternalIPHome> rather than 192.168.2.53 which it does. Blueface's sip server at sip.blueface.ie resolves with no problem on the box. As I mentioned in my earlier email if I register a sip phone with freeswitch directly i can call to blueface using the same extension 1004j as I use for google without any problem. If I modify the extension to bridge to this local sip registration rather than to blueface it also works fine. So bridging local sip to a remote sip registration works and bridging googletalk to a local sip registration works, it only fails when bridging googletalk to a remote sip registration. Strange isn't it? Anyway any help you can offer will be greatly appreciated. Thanks, Kieran. 2007-03-21 16:59:45 [CONSOLE] switch_core.c:4697 switch_core_init_and_modload() Bringing up environment. 2007-03-21 16:59:45 [CONSOLE] switch_core.c:4698 switch_core_init_and_modload() Loading Modules. 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_console] 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_enum] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Dialplan 'enum' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'enum' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'enum' 2007-03-21 16:59:45 [DEBUG] mod_dingaling.c:1772 init_profile() Started Thread for <myUser>@gmail.com/talk@XML 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_dingaling] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:128 switch_loadable_module_process() Adding Endpoint 'dingaling' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'dl_login' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'dl_logout' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:330 switch_loadable_module_process() Adding Chat interface 'jingle' PortAudio version number = 1899 PortAudio version text = 'PortAudio V19-devel' Number of devices = 0 -------------------------------------------------------------------------------- 2007-03-21 16:59:45 [ERR] mod_portaudio.c:1018 load_config() Cannot find an input device! 2007-03-21 16:59:45 [ERR] mod_portaudio.c:1028 load_config() Cannot find an input device! 2007-03-21 16:59:45 [ERR] switch_loadable_module.c:438 switch_loadable_module_load_file() Error Loading module /usr/local/freeswitch/mod/mod_portaudio.so **Module load routine returned an error** 2007-03-21 16:59:45 [CRIT] mod_sofia.c:5558 config_sofia() The <registrations> syntax has been discontinued, please see the new syntax in the default configuration examples 2007-03-21 16:59:45 [NOTICE] mod_sofia.c:5682 config_sofia() Started Profile <FreeswitchServerIP> [sofia_reg_<FreeswitchServerIP>] 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_sofia] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:128 switch_loadable_module_process() Adding Endpoint 'sofia' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:330 switch_loadable_module_process() Adding Chat interface 'sip' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:370 switch_loadable_module_process() Adding Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1] 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_bridgecall] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'bridge' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_commands] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'originate' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'killchan' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'reloadxml' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'load' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'transfer' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'pause' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'show' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'status' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'uuid_bridge' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'session_record' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'broadcast' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'hold' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'media' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'fsctl' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'help' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'version' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_conference] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'conference' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'conference' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:330 switch_loadable_module_process() Adding Chat interface 'conf' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_dptools] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'privacy' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'transfer' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'sleep' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'strftime' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'phrase' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'eval' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'pre_answer' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'answer' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'hangup' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'log' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'info' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'export' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'set' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'unset' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'ring_ready' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'detect_speech' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'ivr' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'redirect' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'queue_dtmf' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'presence' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'strftime' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'chat' su_port(0x80a3228): epoll_create(): Function not implemented 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:235 switch_loadable_module_process() Adding API Function 'strepoch' su_port(0x80a4058): epoll_create(): Function not implemented 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_echo] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'echo' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_playback] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'playback' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'stop_record_session' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'record_session' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'record' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:215 switch_loadable_module_process() Adding Application 'speak' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_dialplan_xml] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:179 switch_loadable_module_process() Adding Dialplan 'XML' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_g711] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000hz 10ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000hz 30ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000hz 60ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMU' (g711 ulaw) 8000hz 120ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000hz 10ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000hz 30ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000hz 60ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'PCMA' (g711 alaw) 8000hz 120ms 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_gsm] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'GSM' (gsm) 8000hz 20ms 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_l16] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 10ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 30ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 60ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 120ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 16000hz 10ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 16000hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 16000hz 30ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 16000hz 60ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 8000hz 120ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 22050hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 32000hz 10ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 32000hz 20ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 32000hz 30ms 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:153 switch_loadable_module_process() Adding Codec 'L16' (raw signed linear (16 bit)) 32000hz 60ms 2007-03-21 16:59:45 [INFO] mod_sndfile.c:298 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-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_sndfile] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'aiff' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'au' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'avr' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'caf' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'htk' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'iff' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'mat' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'paf' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'pvf' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'raw' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'sd2' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'sds' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'sf' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'voc' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'w64' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'wav' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'xi' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'r8' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'r16' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'r24' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'r32' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'gsm' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'ul' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'al' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_native_file] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'PCMA' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'L16' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'PCMU' 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:257 switch_loadable_module_process() Adding File Format 'GSM' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_softtimer] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:197 switch_loadable_module_process() Adding Timer 'soft' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:454 switch_loadable_module_load_file() Successfully Loaded [mod_say_en] 2007-03-21 16:59:45 [NOTICE] switch_loadable_module.c:348 switch_loadable_module_process() Adding Say interface 'en' 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:677 switch_loadable_module_init() open of post_load_modules.conf failed 2007-03-21 16:59:45 [CONSOLE] switch_loadable_module.c:110 switch_loadable_module_runtime() Starting runtime thread for mod_softtimer 2007-03-21 16:59:45 [CONSOLE] switch_core.c:4710 switch_core_init_and_modload() FreeSWITCH Version 1.0.4712 Started. Crash Protection [DISABLED] Max Sessions[1000] freeswitch@spock> 2007-03-21 16:59:46 [DEBUG] libdingaling.c:1151 on_stream() XMPP server connected 2007-03-21 16:59:46 [DEBUG] libdingaling.c:1163 on_stream() XMPP authenticated 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2418 handle_signalling() Creating a session for 3776263765 2007-03-21 16:59:59 [NOTICE] switch_channel.c:347 switch_channel_set_name() New Chan DingaLing/new [9a5ea39a-d7cd-11db-96d7-896d5659d9ab] 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2422 handle_signalling() DingaLing/new State Change CS_NEW -> CS_INIT 2007-03-21 16:59:59 [DEBUG] switch_core.c:3393 switch_core_session_run() (DingaLing/new) State INIT 2007-03-21 16:59:59 [NOTICE] mod_dingaling.c:959 negotiate_media() Ring-Ready DingaLing/new! 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2507 handle_signalling() 12 payloads 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload ISAC 103 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare ISAC 103/16000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload IPCMWB 97 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare IPCMWB 97/16000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload speex 99 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare speex 99/16000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload G723 4 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare G723 4/8000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload speex 98 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare speex 98/8000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload EG711U 100 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare EG711U 100/8000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload EG711A 101 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare EG711A 101/8000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2509 handle_signalling() Available Payload PCMU 0 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2516 handle_signalling() compare PCMU 0/8000 to PCMU 0/8000 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:2527 handle_signalling() Choosing Payload index 0 PCMU 0 2007-03-21 16:59:59 [DEBUG] mod_dingaling.c:929 do_describe() Send Describe [PCMU@8000] nta_agent: received garbage from udp/<MyExternalIPHome>:5060/sip 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2381 handle_signalling() using Existing session for 3776263765 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2575 handle_signalling() 1 candidates 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2583 handle_signalling() candidates 192.168.2.53:2686 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2381 handle_signalling() using Existing session for 3776263765 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2575 handle_signalling() 2 candidates 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2583 handle_signalling() candidates 192.168.2.53:2686 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2583 handle_signalling() candidates <MyExternalIPHome>:2687 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2613 handle_signalling() Acceptable Candidate <MyExternalIPHome>:2687 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:2678 handle_signalling() Creating an identity for 3776263765 <myCallingUser>@ gmail.com/Talk.v104B94A6705 <<myCallingUser>@gmail.com/Talk.v104B94A6705> 1004j 2007-03-21 17:00:01 [NOTICE] switch_channel.c:347 switch_channel_set_name() New Chan DingaLing/1004j [9a5ea39a-d7cd-11db-96d7-896d5659d9ab] 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:861 do_candidates() Send Candidate <FreeswitchServerIP>:16384 [oXcJOdeXEZN9avL6] 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:725 activate_rtp() Set Read Codec to PCMU@8000 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:740 activate_rtp() Set Write Codec to PCMU@8000 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:747 activate_rtp() SETUP RTP <FreeswitchServerIP>:16384 -> <MyExternalIPHome>:2687 2007-03-21 17:00:01 [DEBUG] switch_rtp.c:487 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:1052 channel_on_init() DingaLing/1004j State Change CS_INIT -> CS_RING 2007-03-21 17:00:01 [DEBUG] switch_core.c:3429 switch_core_session_run() (DingaLing/1004j) State RING 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:1069 channel_on_ring() DingaLing/1004j CHANNEL RING 2007-03-21 17:00:01 [DEBUG] switch_core.c:3058 switch_core_standard_on_ring() Standard RING DingaLing/1004j 2007-03-21 17:00:01 [INFO] mod_dialplan_xml.c:219 dialplan_hunt() Processing <myCallingUser>@gmail.com/Talk.v104B94A6705->1004j! 2007-03-21 17:00:01 [DEBUG] mod_dialplan_xml.c:120 parse_exten() test conditions destination_number(1004j) =~ /^1004j/ 2007-03-21 17:00:01 [DEBUG] mod_dialplan_xml.c:355 dialplan_hunt() DingaLing/1004j State Change CS_RING -> CS_EXECUTE 2007-03-21 17:00:01 [DEBUG] switch_core.c:3465 switch_core_session_run() (DingaLing/1004j) State EXECUTE 2007-03-21 17:00:01 [DEBUG] mod_dingaling.c:1086 channel_on_execute() DingaLing/1004j CHANNEL EXECUTE 2007-03-21 17:00:01 [DEBUG] switch_core.c:3117 switch_core_standard_on_execute() Standard EXECUTE 2007-03-21 17:00:01 [NOTICE] switch_core.c:3127 switch_core_standard_on_execute() Execute answer() 2007-03-21 17:00:01 [NOTICE] mod_dptools.c:162 answer_function() Channel [DingaLing/1004j] has been answered 2007-03-21 17:00:01 [NOTICE] switch_core.c:3127 switch_core_standard_on_execute() Execute bridge(sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) 2007-03-21 17:00:01 [NOTICE] switch_channel.c:347 switch_channel_set_name() New Chan sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie[9b63301c-d7cd-11db-96d7-896d5659d9ab] 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:2484 sofia_outgoing_channel() sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie State Change CS_NEW -> CS_INIT 2007-03-21 17:00:01 [DEBUG] switch_core.c:3393 switch_core_session_run() (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) State INIT 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:1323 sofia_on_init() SOFIA INIT 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:4982 event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:2991 sip_i_state() Channel sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie entering state [calling] 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:1336 sofia_on_init() sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie State Change CS_INIT -> CS_RING 2007-03-21 17:00:01 [DEBUG] switch_core.c:3429 switch_core_session_run() (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) State RING 2007-03-21 17:00:01 [DEBUG] mod_sofia.c:1351 sofia_on_ring() SOFIA RING 2007-03-21 17:00:01 [DEBUG] switch_ivr.c:2182 originate_on_ring() sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie State Change CS_RING -> CS_HOLD 2007-03-21 17:00:01 [DEBUG] switch_core.c:3574 switch_core_session_run() (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) State HOLD 2007-03-21 17:00:01 [DEBUG] switch_core.c:3208 switch_core_standard_on_hold() Standard HOLD 2007-03-21 17:00:01 [DEBUG] switch_ivr.c:2836 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:2381 handle_signalling() using Existing session for 3776263765 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:2744 handle_signalling() hungup DingaLing/1004j 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:594 terminate_session() Terminate called from line 2745 state=CS_EXECUTE 2007-03-21 17:00:14 [NOTICE] mod_dingaling.c:609 terminate_session() Hangup DingaLing/1004j [CS_EXECUTE] [NORMAL_CLEARING] 2007-03-21 17:00:14 [INFO] switch_channel.c:993 switch_channel_perform_hangup() Kill DingaLing/1004j [KILL] 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:1182 channel_kill_channel() DingaLing/1004j CHANNEL KILL 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:2746 handle_signalling() End Call 2007-03-21 17:00:14 [NOTICE] switch_ivr.c:3029 switch_ivr_originate() Hangup sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie [CS_HOLD] [ORIGINATOR_CANCEL] 2007-03-21 17:00:14 [INFO] switch_channel.c:993 switch_channel_perform_hangup() Kill sofia/<FreeswitchServerIP>/<exten>@ sip.blueface.ie [KILL] 2007-03-21 17:00:14 [DEBUG] switch_ivr.c:3101 switch_ivr_originate() Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2007-03-21 17:00:14 [INFO] mod_bridgecall.c:74 audio_bridge_function() Originate Failed. Cause: ORIGINATOR_CANCEL 2007-03-21 17:00:14 [DEBUG] switch_core.c:3356 switch_core_session_run() (DingaLing/1004j) State HANGUP 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:1125 channel_on_hangup() NUKE RTP 2007-03-21 17:00:14 [DEBUG] mod_dingaling.c:1137 channel_on_hangup() DingaLing/1004j CHANNEL HANGUP 2007-03-21 17:00:14 [DEBUG] switch_core.c:3048 switch_core_standard_on_hangup() Standard HANGUP DingaLing/1004j, cause: NORMAL_CLEARING 2007-03-21 17:00:14 [DEBUG] switch_core.c:3799 switch_core_session_thread() Session 1 (DingaLing/1004j) Locked, Waiting on external entities 2007-03-21 17:00:14 [INFO] switch_core.c:3804 switch_core_session_thread() Session 1 (DingaLing/1004j) Ended 2007-03-21 17:00:14 [NOTICE] switch_core.c:3680 switch_core_session_destroy() Close Channel DingaLing/1004j 2007-03-21 17:00:14 [DEBUG] switch_core.c:3356 switch_core_session_run() (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) State HANGUP 2007-03-21 17:00:14 [DEBUG] mod_sofia.c:1448 sofia_on_hangup() Channel sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie hanging up, cause: ORIGINATOR_CANCEL 2007-03-21 17:00:14 [DEBUG] mod_sofia.c:1471 sofia_on_hangup() Sending CANCEL to sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie 2007-03-21 17:00:14 [DEBUG] switch_core.c:3048 switch_core_standard_on_hangup() Standard HANGUP sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie, cause: ORIGINATOR_CANCEL 2007-03-21 17:00:14 [DEBUG] switch_core.c:3799 switch_core_session_thread() Session 2 (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) Locked, Waiting on external entities 2007-03-21 17:00:14 [INFO] switch_core.c:3804 switch_core_session_thread() Session 2 (sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie) Ended 2007-03-21 17:00:14 [NOTICE] switch_core.c:3680 switch_core_session_destroy() Close Channel sofia/<FreeswitchServerIP>/<exten>@sip.blueface.ie