[Freeswitch-users] skypiax - CALL FAILUREREASON 7 = Sound I/O error

Giovanni Maruzzelli gmaruzz at celliax.org
Thu Apr 30 23:20:10 PDT 2009


Have a happy MayDay!

I cannot see the whole mail now, it's clipped for my mobile, but it
seems the nth bizarry of new alsa config file, that creates an hdmi
device even if you do not have one. Try to edit
/usr/share/alsa/alsa.conf or any other file in /usr/share/alsa dir and
delete any mention of 'hdmi'.
If this do not works, please file a jira or write again.
Giovanni



On 5/1/09, Anthony Minessale <anthony.minessale at gmail.com> wrote:
> if you put that info in a jira ticket
>
> http://jira.freeswitch.org
>
> and route it to skypeiax , the guy who maintains that module will see it.
>
>
> On Thu, Apr 30, 2009 at 5:37 PM, <can_man at gmx.de> wrote:
>
>>
>> Hello,
>>
>> I am trying to get skypiax working, but I am having trouble with the
>> sound.
>> The calls fail with CALL FAILUREREASON 7 = Sound I/O error and
>> I am getting the following error:
>>
>>        ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM
>> cards.pcm.hdmi
>>
>>
>> I am running centos 5.3 and have followed the installation guide on the
>> wiki. CaptureDevice, RingDevice and SoundDevice are all set to 2. When
>> saving
>> the configuration on my desktop I have set the sound card to snd_dummy. On
>> the server the startup script load snd-dumy like this /sbin/modprobe
>> snd-dummy enable=1.
>> Below is the output of lsmod and the debug output from FS. It would be
>> great if someone could help me fix my problem.
>>
>> Thank you very much.
>> Best wishes,
>> Phil
>>
>>
>>
>>
>> -bash-3.2# lsmod
>> Module                  Size  Used by
>> snd_dummy              12416  0
>> snd_seq_oss            32832  0
>> snd_seq_midi_event      7744  1 snd_seq_oss
>> snd_seq                55200  4 snd_seq_oss,snd_seq_midi_event
>> snd_seq_device          7120  1 snd_seq_oss
>> snd_pcm_oss            44480  0
>> snd_mixer_oss          16512  1 snd_pcm_oss
>> snd_pcm                79624  2 snd_dummy,snd_pcm_oss
>> snd_timer              22088  2 snd_seq,snd_pcm
>> snd                    55976  8
>> snd_dummy,snd_seq_oss,snd_seq,snd_seq_device,snd_pcm_oss,snd_mixer_oss,snd_pcm,snd_timer
>> soundcore               7456  1 snd
>> snd_page_alloc          8720  1 snd_pcm
>>
>>
>>
>> freeswitch at voipserverServerFreeswitch> load mod_skypiax
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:718 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  718  ][none      ][-1,-1,-1]
>> globals.debug=0
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:720 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  720  ][none      ][-1,-1,-1]
>> globals.debug=8
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:731 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  731  ][none      ][-1,-1,-1]
>> codec-master
>> globals.debug=8
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:734 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  734  ][none      ][-1,-1,-1]
>> globals.dialplan=XML
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:740 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  740  ][none      ][-1,-1,-1]
>> globals.context=default
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:743 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  743  ][none      ][-1,-1,-1]
>> globals.codec_string=gsm,ulaw
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:750 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  750  ][none      ][-1,-1,-1]
>> globals.codec_rates_string=8000,16000
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:723 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  723  ][none      ][-1,-1,-1]
>> globals.hold_music=
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:737 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  737  ][none      ][-1,-1,-1]
>> globals.destination=5000
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:847 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  847  ][none      ][-1,-1,-1]
>> interface_id=1
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:870 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  870  ][none      ][-1,-1,-1]
>> name=skypiax1
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:876 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  876  ][none      ][-1,-1,-1] Initialized
>> XInitThreads!
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:897 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  897  ][skypiax1  ][-1, 0, 0] CONFIGURING
>> interface_id=1
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:920 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  920  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].X11_display=:101
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:924 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  924  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].skype_user=xyzUK
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:928 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  928  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].tcp_cli_port=15556
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:932 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  932  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].tcp_srv_port=15557
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:935 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  935  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].name=skypiax1
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:938 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  938  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].context=default
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:942 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  942  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].dialplan=XML
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:946 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  946  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].destination=3101
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:949 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  949  ][skypiax1  ][-1, 0, 0]
>> interface_id=1 globals.SKYPIAX_INTERFACES[interface_id].context=default
>> 2009-04-30 17:47:35 [WARNING] mod_skypiax.c:950 load_config() rev
>> 13177[(nil)|37     ][WARNINGA  950  ][skypiax1  ][-1, 0, 0] STARTING
>> interface_id=1
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:1407
>> skypiax_do_skypeapi_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE
>> 1407
>> ][skypiax1  ][-1, 0, 0] X Display ':101' opened
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:1309 skypiax_present() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1309 ][none      ][-1,-1,-1] Skype
>> instance found with id #2097454
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:661
>> skypiax_signaling_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE  661
>>  ][skypiax1  ][-1, 0, 0] In skypiax_signaling_thread_func: started,
>> p=0x2aaab93226f8
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||OK|||
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||PROTOCOL 7|||
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||CONNSTATUS ONLINE|||
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||CURRENTUSERHANDLE xyzUK|||
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:111
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  111  ][skypiax1  ][-1, 0, 0] Skype
>> MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE,
>> cuh:
>> xyzUK, skype_user: xyzUK!
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||USERSTATUS ONLINE|||
>> 2009-04-30 17:47:35 [NOTICE] mod_skypiax.c:976 load_config() rev
>> 13177[(nil)|37     ][NOTICA  976  ][skypiax1  ][-1, 0, 0] WAITING roughly
>> 10
>> seconds to find a running Skype client and connect to its SKYPE API for
>> interface_id=1
>> 2009-04-30 17:47:35 [NOTICE] mod_skypiax.c:986 load_config() rev
>> 13177[(nil)|37     ][NOTICA  986  ][skypiax1  ][-1, 0, 0] Found a running
>> Skype client, connected to its SKYPE API for interface_id=1, waiting 60
>> seconds for CURRENTUSERHANDLE==xyzUK
>> 2009-04-30 17:47:35 [WARNING] mod_skypiax.c:1004 load_config() rev
>> 13177[(nil)|37     ][WARNINGA  1004 ][skypiax1  ][-1, 0, 0] Interface_id=1
>> is now STARTED, the Skype client to which we are connected gave us the
>> correct CURRENTUSERHANDLE (xyzUK)
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:847 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  847  ][none      ][-1,-1,-1]
>> interface_id=2
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:870 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  870  ][none      ][-1,-1,-1]
>> name=skypiax2
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:876 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  876  ][none      ][-1,-1,-1] Initialized
>> XInitThreads!
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:897 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  897  ][skypiax2  ][-1, 0, 0] CONFIGURING
>> interface_id=2
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:920 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  920  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].X11_display=:102
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:924 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  924  ][skypiax2  ][-1, 0, 0]
>> interface_id=2
>> globals.SKYPIAX_INTERFACES[interface_id].skype_user=voipserver
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:928 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  928  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].tcp_cli_port=15558
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:932 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  932  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].tcp_srv_port=15559
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:935 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  935  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].name=skypiax2
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:938 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  938  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].context=default
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:942 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  942  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].dialplan=XML
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:946 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  946  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].destination=5000
>> 2009-04-30 17:47:35 [DEBUG] mod_skypiax.c:949 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  949  ][skypiax2  ][-1, 0, 0]
>> interface_id=2 globals.SKYPIAX_INTERFACES[interface_id].context=default
>> 2009-04-30 17:47:35 [WARNING] mod_skypiax.c:950 load_config() rev
>> 13177[(nil)|37     ][WARNINGA  950  ][skypiax2  ][-1, 0, 0] STARTING
>> interface_id=2
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:1407
>> skypiax_do_skypeapi_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE
>> 1407
>> ][skypiax2  ][-1, 0, 0] X Display ':102' opened
>> 2009-04-30 17:47:35 [DEBUG] skypiax_protocol.c:1309 skypiax_present() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1309 ][none      ][-1,-1,-1] Skype
>> instance found with id #2097454
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:661
>> skypiax_signaling_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE  661
>>  ][skypiax2  ][-1, 0, 0] In skypiax_signaling_thread_func: started,
>> p=0x2aaab9325c18
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax2  ][-1, 0, 0]
>> READING:
>> |||OK|||
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax2  ][-1, 0, 0]
>> READING:
>> |||PROTOCOL 7|||
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax2  ][-1, 0, 0]
>> READING:
>> |||CONNSTATUS ONLINE|||
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax2  ][-1, 0, 0]
>> READING:
>> |||CURRENTUSERHANDLE voipserver|||
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:111
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  111  ][skypiax2  ][-1, 0, 0] Skype
>> MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE,
>> cuh:
>> voipserver, skype_user: voipserver!
>> 2009-04-30 17:47:36 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax2  ][-1, 0, 0]
>> READING:
>> |||USERSTATUS ONLINE|||
>> 2009-04-30 17:47:36 [NOTICE] mod_skypiax.c:976 load_config() rev
>> 13177[(nil)|37     ][NOTICA  976  ][skypiax2  ][-1, 0, 0] WAITING roughly
>> 10
>> seconds to find a running Skype client and connect to its SKYPE API for
>> interface_id=2
>> 2009-04-30 17:47:36 [NOTICE] mod_skypiax.c:986 load_config() rev
>> 13177[(nil)|37     ][NOTICA  986  ][skypiax2  ][-1, 0, 0] Found a running
>> Skype client, connected to its SKYPE API for interface_id=2, waiting 60
>> seconds for CURRENTUSERHANDLE==voipserver
>> API CALL [load(mod_skypiax)] output:
>> +OK
>>
>> 2009-04-30 17:47:36 [WARNING] mod_skypiax.c:1004 load_config() rev
>> 13177[(nil)|37     ][WARNINGA  1004 ][skypiax2  ][-1, 0, 0] Interface_id=2
>> is now STARTED, the Skype client to which we are connected gave us the
>> correct CURRENTUSERHANDLE (voipserver)
>>
>>
>>
>>
>>
>>
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1028 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1028 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].interface_id=1
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1030 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1030 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].X11_display=:101
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1032 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1032 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].name=skypiax1
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1034 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1034 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].context=default
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1036 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1036 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].dialplan=XML
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1038 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1038 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].destination=3101
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1040 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1040 ][skypiax1  ][-1, 0, 0] i=1
>> globals.SKYPIAX_INTERFACES[1].context=default
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1028 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1028 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].interface_id=2
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1030 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1030 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].X11_display=:102
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1032 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1032 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].name=skypiax2
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1034 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1034 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].context=default
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1036 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1036 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].dialplan=XML
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1038 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1038 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].destination=5000
>> 2009-04-30 17:47:36 [DEBUG] mod_skypiax.c:1040 load_config() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  1040 ][skypiax2  ][-1, 0, 0] i=2
>> globals.SKYPIAX_INTERFACES[2].context=default
>> 2009-04-30 17:47:36 [CONSOLE] switch_loadable_module.c:889
>> switch_loadable_module_load_file() Successfully Loaded [mod_skypiax]
>> 2009-04-30 17:47:36 [NOTICE] switch_loadable_module.c:142
>> switch_loadable_module_process() Adding Endpoint 'skypiax'
>> 2009-04-30 17:47:36 [NOTICE] switch_loadable_module.c:270
>> switch_loadable_module_process() Adding API Function 'sk'
>> 2009-04-30 17:47:36 [NOTICE] switch_loadable_module.c:270
>> switch_loadable_module_process() Adding API Function 'skypiax'
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch> 2009-04-30 17:52:41 [DEBUG]
>> skypiax_protocol.c:57 skypiax_signaling_read() rev 13177[(nil)|37
>> ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0] READING: |||USER paolofun6
>> PHONE_MOBILE +420775216536|||
>>
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch>
>> freeswitch at voipserverServerFreeswitch> 2009-04-30 17:52:49 [NOTICE]
>> switch_channel.c:602 switch_channel_set_name() New Channel sofia/external/
>> 07771236762 at sipgate.co.uk [fc670e69-1143-4241-8364-3158f1ffa6ef]
>> 2009-04-30 17:52:49 [DEBUG] sofia.c:2912 sofia_handle_sip_i_state()
>> Channel
>> sofia/external/07771236762 at sipgate.co.uk entering state [received][100]
>> 2009-04-30 17:52:49 [DEBUG] sofia.c:2919 sofia_handle_sip_i_state() Remote
>> SDP:
>> v=0
>> o=root 15141 15141 IN IP4 217.10.66.71
>> s=session
>> c=IN IP4 217.10.66.71
>> t=0 0
>> m=audio 12950 RTP/AVP 8 0 3 97 18 112 101
>> a=rtpmap:8 PCMA/8000
>> a=rtpmap:0 PCMU/8000
>> a=rtpmap:3 GSM/8000
>> a=rtpmap:97 iLBC/8000
>> a=fmtp:97 mode=30
>> a=rtpmap:18 G729/8000
>> a=fmtp:18 annexb=no
>> a=rtpmap:112 G726-32/8000
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-16
>> a=silenceSupp:off - - - -
>> a=ptime:20
>>
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:2931 sofia_glue_negotiate_sdp()
>> Audio Codec Compare [PCMA:8:8000:20]/[SPEEX:98:8000:20]
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:2931 sofia_glue_negotiate_sdp()
>> Audio Codec Compare [PCMA:8:8000:20]/[SPEEX:99:16000:20]
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:2931 sofia_glue_negotiate_sdp()
>> Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20]
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:2931 sofia_glue_negotiate_sdp()
>> Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20]
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:1912 sofia_glue_tech_set_codec()
>> Set Codec sofia/external/07771236762 at sipgate.co.uk PCMA/8000 20 ms 160
>> samples
>> 2009-04-30 17:52:49 [DEBUG] sofia_glue.c:2891 sofia_glue_negotiate_sdp()
>> Set 2833 dtmf payload to 101
>> 2009-04-30 17:52:49 [DEBUG] sofia.c:3078 sofia_handle_sip_i_state()
>> (sofia/external/07771236762 at sipgate.co.uk) State Change CS_NEW -> CS_INIT
>> 2009-04-30 17:52:49 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [BREAK]
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk)
>> Running State Change CS_INIT
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:480
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> INIT
>> 2009-04-30 17:52:49 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/external/
>> 07771236762 at sipgate.co.uk SOFIA INIT
>> 2009-04-30 17:52:49 [DEBUG] mod_sofia.c:111 sofia_on_init()
>> (sofia/external/07771236762 at sipgate.co.uk) State Change CS_INIT ->
>> CS_ROUTING
>> 2009-04-30 17:52:49 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [BREAK]
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:480
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> INIT going to sleep
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk)
>> Running State Change CS_ROUTING
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:483
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> ROUTING
>> 2009-04-30 17:52:49 [DEBUG] mod_sofia.c:130 sofia_on_routing()
>> sofia/external/07771236762 at sipgate.co.uk SOFIA ROUTING
>> 2009-04-30 17:52:49 [DEBUG] switch_core_state_machine.c:78
>> switch_core_standard_on_routing()
>> sofia/external/07771236762 at sipgate.co.ukStandard ROUTING
>> 2009-04-30 17:52:49 [INFO] mod_dialplan_xml.c:252 dialplan_hunt()
>> Processing 07771236762->00442083324655 in context public
>> Dialplan: sofia/external/07771236762 at sipgate.co.uk parsing
>> [public->skype_uri] continue=false
>> Dialplan: sofia/external/07771236762 at sipgate.co.uk Regex (PASS)
>> [skype_uri] destination_number(00442083324655) =~ /^(00442083324655)$/
>> break=on-false
>> Dialplan: sofia/external/07771236762 at sipgate.co.uk Action
>> bridge(skypiax/skypiax1/xyzTestUK)
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:114
>> switch_core_standard_on_routing() (sofia/external/
>> 07771236762 at sipgate.co.uk) State Change CS_ROUTING -> CS_EXECUTE
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:483
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> ROUTING going to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk)
>> Running State Change CS_EXECUTE
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:490
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> EXECUTE
>> 2009-04-30 17:52:51 [DEBUG] mod_sofia.c:173 sofia_on_execute()
>> sofia/external/07771236762 at sipgate.co.uk SOFIA EXECUTE
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:151
>> switch_core_standard_on_execute()
>> sofia/external/07771236762 at sipgate.co.ukStandard EXECUTE
>> EXECUTE
>> sofia/external/07771236762 at sipgate.co.ukbridge(skypiax/skypiax1/xyzTestUK)
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:585 channel_outgoing_channel()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  585  ][          ][-1, 0, 0]
>> globals.SKYPIAX_INTERFACES[1].name=|||skypiax1|||?
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:151 skypiax_tech_init() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  151  ][skypiax1  ][-1, 0, 0]
>> skypiax_codec
>> SUCCESS
>> 2009-04-30 17:52:51 [NOTICE] switch_channel.c:602
>> switch_channel_set_name()
>> New Channel skypiax/skypiax1/xyzTestUK
>> [0375c668-b4a2-4364-a8c6-0a718d4f00a3]
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:773 skypiax_call() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  773  ][skypiax1  ][-1, 0, 0] Calling
>> Skype, rdest is: xyzTestUK
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:1262
>> skypiax_signaling_write() rev 13177[(nil)|37     ][DEBUG_SKYPE  1262
>> ][skypiax1  ][-1, 0, 0] SENDING: |||SET AGC OFF||||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> ||||||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:1262
>> skypiax_signaling_write() rev 13177[(nil)|37     ][DEBUG_SKYPE  1262
>> ][skypiax1  ][-1, 0, 0] SENDING: |||SET AEC OFF||||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> ||||||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:1262
>> skypiax_signaling_write() rev 13177[(nil)|37     ][DEBUG_SKYPE  1262
>> ][skypiax1  ][-1, 0, 0] SENDING: |||CALL xyzTestUK||||
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:642 channel_outgoing_channel()
>> (skypiax/skypiax1/xyzTestUK) State Change CS_NEW -> CS_INIT
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal
>> skypiax/skypiax1/xyzTestUK [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:300 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  300  ][skypiax1  ][-1, 0, 0]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_BREAK
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) Running State
>> Change
>> CS_INIT
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:480
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State INIT
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:177 channel_on_init()
>> (skypiax/skypiax1/xyzTestUK) State Change CS_INIT -> CS_ROUTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal
>> skypiax/skypiax1/xyzTestUK [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:300 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  300  ][skypiax1  ][-1, 0, 0]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_BREAK
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:182 channel_on_init() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  182  ][skypiax1  ][-1, 0, 0]
>> skypiax/skypiax1/xyzTestUK CHANNEL INIT
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:480
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State INIT going to
>> sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) Running State
>> Change
>> CS_ROUTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:483
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State ROUTING
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:257 channel_on_routing() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  257  ][skypiax1  ][-1, 0, 0]
>> skypiax/skypiax1/xyzTestUK CHANNEL ROUTING
>> 2009-04-30 17:52:51 [DEBUG] switch_ivr_originate.c:63
>> originate_on_routing() (skypiax/skypiax1/xyzTestUK) State Change
>> CS_ROUTING
>> -> CS_CONSUME_MEDIA
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal
>> skypiax/skypiax1/xyzTestUK [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:300 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  300  ][skypiax1  ][-1, 0, 0]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_BREAK
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:483
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State ROUTING going
>> to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) Running State
>> Change
>> CS_CONSUME_MEDIA
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:502
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State CONSUME_MEDIA
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||AGC OFF|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||AEC OFF|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 0, 0]
>> READING:
>> |||CALL 455 STATUS UNPLACED|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:167
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  167  ][skypiax1  ][-1, 0, 0] Skype
>> MSG: message: CALL, obj: CALL, id: 455, prop: STATUS, value:
>> UNPLACED,where:
>> NULL!
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:371
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  371  ][skypiax1  ][-1, 3,116]
>> skype_call: 455 is now UNPLACED
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> ALSA lib pcm.c:2184:(snd_pcm_open_noupdate) Unknown PCM cards.pcm.hdmi
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 3,116]
>> READING:
>> |||CALL 455 STATUS ROUTING|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:167
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  167  ][skypiax1  ][-1, 3,116] Skype
>> MSG: message: CALL, obj: CALL, id: 455, prop: STATUS, value:
>> ROUTING,where:
>> NULL!
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:365
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  365  ][skypiax1  ][-1, 3,117]
>> skype_call: 455 is now ROUTING
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 3,117]
>> READING:
>> |||CALL 455 FAILUREREASON 7|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:167
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  167  ][skypiax1  ][-1, 3,117] Skype
>> MSG: message: CALL, obj: CALL, id: 455, prop: FAILUREREASON, value:
>> 7,where:
>> NULL!
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:201
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  201  ][skypiax1  ][-1, 3,117] Skype
>> FAILED on skype_call 455. Let's wait for the FAILED message.
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 3,117]
>> READING:
>> |||CALL 455 VAA_INPUT_STATUS FALSE|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:167
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  167  ][skypiax1  ][-1, 3,117] Skype
>> MSG: message: CALL, obj: CALL, id: 455, prop: VAA_INPUT_STATUS, value:
>> FALSE,where: NULL!
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 3,117]
>> READING:
>> |||CALL 455 STATUS FAILED|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:167
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  167  ][skypiax1  ][-1, 3,117] Skype
>> MSG: message: CALL, obj: CALL, id: 455, prop: STATUS, value: FAILED,where:
>> NULL!
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:334
>> skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  334  ][skypiax1  ][-1, 3,112] we
>> tried
>> to call Skype on skype_call 455 and Skype has now FAILED
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:672
>> skypiax_signaling_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE  672
>>  ][skypiax1  ][-1, 1,112] skype call ended
>> 2009-04-30 17:52:51 [NOTICE] mod_skypiax.c:680
>> skypiax_signaling_thread_func() Hangup skypiax/skypiax1/xyzTestUK
>> [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
>> 2009-04-30 17:52:51 [DEBUG] switch_channel.c:1641
>> switch_channel_perform_hangup() Send signal skypiax/skypiax1/xyzTestUK
>> [KILL]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:293 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  293  ][skypiax1  ][-1, 1,112]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_KILL
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal
>> skypiax/skypiax1/xyzTestUK [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:300 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  300  ][skypiax1  ][-1, 1,112]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_BREAK
>> 2009-04-30 17:52:51 [DEBUG] switch_ivr_originate.c:2086
>> switch_ivr_originate() Originate Resulted in Error Cause: 16
>> [NORMAL_CLEARING]
>> 2009-04-30 17:52:51 [INFO] mod_dptools.c:2074 audio_bridge_function()
>> Originate Failed.  Cause: NORMAL_CLEARING
>> 2009-04-30 17:52:51 [NOTICE] mod_dptools.c:2106 audio_bridge_function()
>> Hangup sofia/external/07771236762 at sipgate.co.uk [CS_EXECUTE]
>> [NORMAL_CLEARING]
>> 2009-04-30 17:52:51 [DEBUG] switch_channel.c:1641
>> switch_channel_perform_hangup() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [KILL]
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:490
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> EXECUTE going to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk)
>> Running State Change CS_HANGUP
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> HANGUP
>> 2009-04-30 17:52:51 [DEBUG] mod_sofia.c:323 sofia_on_hangup() Channel
>> sofia/external/07771236762 at sipgate.co.uk hanging up, cause:
>> NORMAL_CLEARING
>> 2009-04-30 17:52:51 [DEBUG] mod_sofia.c:399 sofia_on_hangup() Responding
>> to
>> INVITE with: 480
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup()
>> sofia/external/07771236762 at sipgate.co.ukStandard HANGUP, cause:
>> NORMAL_CLEARING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> HANGUP going to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:475
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> Change CS_HANGUP -> CS_REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal sofia/external/
>> 07771236762 at sipgate.co.uk [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk)
>> Running State Change CS_REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:609
>> switch_core_session_reporting_state() (sofia/external/
>> 07771236762 at sipgate.co.uk) State REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:502
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State CONSUME_MEDIA
>> going to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) Running State
>> Change
>> CS_HANGUP
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State HANGUP
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:228 channel_on_hangup() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  228  ][skypiax1  ][-1, 1,112] hanging up
>> skype call: 455
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:1262
>> skypiax_signaling_write() rev 13177[(nil)|37     ][DEBUG_SKYPE  1262
>> ][skypiax1  ][-1, 1,112] SENDING: |||ALTER CALL 455 HANGUP||||
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:235 channel_on_hangup() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  235  ][skypiax1  ][-1, 1,112]
>> skypiax/skypiax1/xyzTestUK CHANNEL HANGUP
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:46
>> switch_core_standard_on_hangup() skypiax/skypiax1/xyzTestUK Standard
>> HANGUP,
>> cause: NORMAL_CLEARING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:433
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State HANGUP going
>> to
>> sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:475
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State Change
>> CS_HANGUP -> CS_REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:927
>> switch_core_session_signal_state_change() Send signal
>> skypiax/skypiax1/xyzTestUK [BREAK]
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:300 channel_kill_channel() rev
>> 13177[(nil)|37     ][DEBUG_SKYPE  300  ][skypiax1  ][-1, 1,112]
>> skypiax/skypiax1/xyzTestUK CHANNEL got SWITCH_SIG_BREAK
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:397
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) Running State
>> Change
>> CS_REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:609
>> switch_core_session_reporting_state() (skypiax/skypiax1/xyzTestUK) State
>> REPORTING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:53
>> switch_core_standard_on_reporting() skypiax/skypiax1/xyzTestUK Standard
>> REPORTING, cause: NORMAL_CLEARING
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:609
>> switch_core_session_reporting_state() (skypiax/skypiax1/xyzTestUK) State
>> REPORTING going to sleep
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (skypiax/skypiax1/xyzTestUK) State Change
>> CS_REPORTING -> CS_DESTROY
>> 2009-04-30 17:52:51 [DEBUG] switch_core_session.c:1061
>> switch_core_session_thread() Session 2 (skypiax/skypiax1/xyzTestUK)
>> Locked,
>> Waiting on external entities
>> 2009-04-30 17:52:51 [NOTICE] switch_core_session.c:1079
>> switch_core_session_thread() Session 2 (skypiax/skypiax1/xyzTestUK) Ended
>> 2009-04-30 17:52:51 [NOTICE] switch_core_session.c:1081
>> switch_core_session_thread() Close Channel skypiax/skypiax1/xyzTestUK
>> [CS_DESTROY]
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:559
>> switch_core_session_destroy_state() (skypiax/skypiax1/xyzTestUK) State
>> DESTROY
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:60
>> switch_core_standard_on_destroy() skypiax/skypiax1/xyzTestUK Standard
>> DESTROY
>> 2009-04-30 17:52:51 [DEBUG] switch_core_state_machine.c:559
>> switch_core_session_destroy_state() (skypiax/skypiax1/xyzTestUK) State
>> DESTROY going to sleep
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:57 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  57   ][skypiax1  ][-1, 1,112]
>> READING:
>> |||ERROR 559 CALL: Action failed|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:91 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  91   ][skypiax1  ][-1, 1,112] Skype
>> got ERROR: |||ERROR|||
>> 2009-04-30 17:52:51 [DEBUG] skypiax_protocol.c:93 skypiax_signaling_read()
>> rev 13177[(nil)|37     ][DEBUG_SKYPE  93   ][skypiax1  ][-1, 1,110]
>> skype_call now is DOWN
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:672
>> skypiax_signaling_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE  672
>>  ][skypiax1  ][-1, 1,110] skype call ended
>> 2009-04-30 17:52:51 [DEBUG] mod_skypiax.c:687
>> skypiax_signaling_thread_func() rev 13177[(nil)|37     ][DEBUG_SKYPE  687
>>  ][skypiax1  ][-1, 1,110] no session
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:53
>> switch_core_standard_on_reporting() sofia/external/
>> 07771236762 at sipgate.co.uk Standard REPORTING, cause: NORMAL_CLEARING
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:609
>> switch_core_session_reporting_state() (sofia/external/
>> 07771236762 at sipgate.co.uk) State REPORTING going to sleep
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:410
>> switch_core_session_run() (sofia/external/07771236762 at sipgate.co.uk) State
>> Change CS_REPORTING -> CS_DESTROY
>> 2009-04-30 17:52:54 [DEBUG] switch_core_session.c:1061
>> switch_core_session_thread() Session 1 (sofia/external/
>> 07771236762 at sipgate.co.uk) Locked, Waiting on external entities
>> 2009-04-30 17:52:54 [NOTICE] switch_core_session.c:1079
>> switch_core_session_thread() Session 1 (sofia/external/
>> 07771236762 at sipgate.co.uk) Ended
>> 2009-04-30 17:52:54 [NOTICE] switch_core_session.c:1081
>> switch_core_session_thread() Close Channel sofia/external/
>> 07771236762 at sipgate.co.uk [CS_DESTROY]
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:559
>> switch_core_session_destroy_state() (sofia/external/
>> 07771236762 at sipgate.co.uk) State DESTROY
>> 2009-04-30 17:52:54 [DEBUG] mod_sofia.c:240 sofia_on_destroy()
>> sofia/external/07771236762 at sipgate.co.uk SOFIA DESTROY
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:60
>> switch_core_standard_on_destroy()
>> sofia/external/07771236762 at sipgate.co.ukStandard DESTROY
>> 2009-04-30 17:52:54 [DEBUG] switch_core_state_machine.c:559
>> switch_core_session_destroy_state() (sofia/external/
>> 07771236762 at sipgate.co.uk) State DESTROY going to sleep
>> --
>> Neu: GMX FreeDSL Komplettanschluss mit DSL 6.000 Flatrate +
>> Telefonanschluss für nur 17,95 Euro/mtl.!*
>> http://dslspecial.gmx.de/freedsl-surfflat/?ac=OM.AD.PD003K11308T4569a
>>
>> _______________________________________________
>> Freeswitch-users mailing list
>> Freeswitch-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>
>
>
> --
> Anthony Minessale II
>
> FreeSWITCH http://www.freeswitch.org/
> ClueCon http://www.cluecon.com/
>
> AIM: anthm
> MSN:anthony_minessale at hotmail.com <MSN%3Aanthony_minessale at hotmail.com>
> GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com<PAYPAL%3Aanthony.minessale at gmail.com>
> IRC: irc.freenode.net #freeswitch
>
> FreeSWITCH Developer Conference
> sip:888 at conference.freeswitch.org <sip%3A888 at conference.freeswitch.org>
> iax:guest at conference.freeswitch.org/888
> googletalk:conf+888 at conference.freeswitch.org<googletalk%3Aconf%2B888 at conference.freeswitch.org>
> pstn:213-799-1400
>

-- 
Sent from my mobile device

Sincerely,

Giovanni Maruzzelli
=========================================
www.celliax.org
via Pierlombardo 9, 20135 Milano
Italy
gmaruzz at celliax dot org
Cell : +39-347-2665618
Fax : +39-02-87390039




More information about the FreeSWITCH-users mailing list