[Freeswitch-users] skypiax - CALL FAILUREREASON 7 = Sound I/O error
Giovanni Maruzzelli
gmaruzz at celliax.org
Fri May 1 12:25:43 PDT 2009
Hi Phil,
I just tried all the steps (exactly, just cut and paste) from the wiki page:
http://wiki.freeswitch.org/wiki/Skypiax_Skype_Endpoint_and_Trunk#An_example_of_Skypiax_and_FreeSWITCH_installation_on_CentOS_5.2.2C_from_scratch
I substituted 5.3 instead of 5.2.
I'm afraid it worked flawlessly for me. (shocked about: Anthony is
right about CentOS being "boring and predictable", good qualities for
a server OS!)
At the start of Skype clients it will tell bizarre things about hdmi,
but they are unharmful (I've not edited the alsa stuff, it still groak
about non-existent hdmi, but it works nonetheless).
So, I suspect your problems have some other cause.
Now I go read the Jira and the attached files, and I hope to be more of help.
-giovanni
On Fri, May 1, 2009 at 4:14 PM, Giovanni Maruzzelli <gmaruzz at celliax.org> wrote:
> Gruss Phil,
>
> actually it was shooting in the dark from my side, because I not yet
> tested centos5.3, only centos5.2
>
> As soon as I test it out I'll be back to you.
> Thanks for filing the Jira.
>
> -giovanni
>
>
> On Fri, May 1, 2009 at 1:19 PM, <can_man at gmx.de> wrote:
>> Ciao Giovanni,
>>
>> grazie per la tua risposta. Removing 'hdmi' did make some changes, but it
>> still doesn't work. I have filed a jira:
>>
>> http://jira.freeswitch.org/browse/MODSKYPIAX-33
>>
>> Buon primo maggio anche a te,
>> Phil
>>
>> -------- Original-Nachricht --------
>>> Datum: Fri, 1 May 2009 08:20:10 +0200
>>> Von: Giovanni Maruzzelli <gmaruzz at celliax.org>
>>> An: freeswitch-users at lists.freeswitch.org
>>> Betreff: Re: [Freeswitch-users] skypiax - CALL FAILUREREASON 7 = Sound I/O error
>>
>>> 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
>>>
>>> _______________________________________________
>>> 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
>>
>> --
>> 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
>>
>
More information about the FreeSWITCH-users
mailing list