[Freeswitch-users] skypiax - CALL FAILUREREASON 7 = Sound I/O error
can_man at gmx.de
can_man at gmx.de
Thu Apr 30 15:37:01 PDT 2009
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.uk Standard 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.uk Standard EXECUTE
EXECUTE sofia/external/07771236762 at sipgate.co.uk bridge(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.uk Standard 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.uk Standard 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
More information about the FreeSWITCH-users
mailing list