[Freeswitch-users] skypiax - CALL FAILUREREASON 7 = Sound I/O error
can_man at gmx.de
can_man at gmx.de
Fri May 1 15:47:49 PDT 2009
Hello,
thank you very much for your help. So annoying it was actually a
permissions problem, I had checked but missed the one that was crutial.
Cheers,
Phil
-------- Original-Nachricht --------
> Datum: Fri, 1 May 2009 22:26:44 +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
> Hi Phil,
>
> I had to close the Jira, please try again with your original
> alsa.conf. Your editing of it was probably causing some of the new
> problems.
>
> I just tested it all in a virtual machine (using virtualbox) and it
> worked for me.
>
> Only things that comes at my mind is that I used the 32bit, not the
> 64bit version.
>
> You are using 64bit in a Xen environment (if I understood correctly),
> but others have done it with success (btw, various deployment in
> Amazon ec2).
>
> The error you was receiving in the original post (ERROR 7) is the
> Skype client not finding the sound device.
>
> Maybe is just a problem of permissions? The user the Skype client
> instance is started as has permission to read/write on the sound
> device?
>
> Have you tried it starting Skype instance as root user?
>
> In my test deployment here, ls -l /dev/snd/* shows that the devices
> are r/w only by root...
> Change the permission of the devices if you start Skype as another user.
>
> chmod -R a+rw /dev/snd
>
> So, please go back to the original alsa.conf ( I will mail it to your
> address), then be sure to follow all the steps.
>
> Then, as a first test, try a call to "echo123" that is the test call
> answering machine made available by Skype.
>
> Let me know.
>
> -giovanni
>
>
> On Fri, May 1, 2009 at 9:25 PM, Giovanni Maruzzelli <gmaruzz at celliax.org>
> wrote:
> > 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
> >>>
> >>
> >
>
> _______________________________________________
> 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
--
Psssst! Schon vom neuen GMX MultiMessenger gehört? Der kann`s mit allen: http://www.gmx.net/de/go/multimessenger01
More information about the FreeSWITCH-users
mailing list