Upgraded from 1.0.4 pre8 to the latest Git tree. Skype does not work anymore.
Hi, On weekend I have upgraded to the latest Git tree from 1.0.4 pre8 which I was running for a long time, year may be. Everything went smooth, except Skype does not work anymore. Basically I followed Download & Installation Guide (http://wiki.freeswitch.org/wiki/Installation_Guide) and Mod skypopen Skype Endpoint and Trunk (http://wiki.freeswitch.org/wiki/Mod_skypopen_Skype_Endpoint_and_Trunk). The only difference, I have added <load module="mod_skypopen"/> at the end of the modules.conf.xml file to load skypopen module. My impression is that mod_skypopen hangs during initialization and freeswitch or some of its parts are locked and do not respond properly. When I call any number, I am getting busy signal immediately after keying the number. Log file shows: 2010-06-28 18:58:40.334877 [CRIT] switch_core_session.c:1524 Throttle Error! 0 In my case freeswitch and skype are started automatically by the scripts that are basically recommended ones. I have tried to load mod_skypopen manually (I removed mod_skypopen from the modules.conf.xml file) and most of the time it hangs. 1. I start the computer, freeswitch and skype autostart, but freeswitch does load mod_skypopen (it is not in the modules.conf.xml) 2. I shutdown freeswitch and start it from the console and wait for its prompt. 3. I do load mod_skypopen from the prompt. 4. I do load mod_skypopen from the prompt. 5. At this time it never returns to the prompt and there is no ringtone, log file shows: 2010-06-28 18:16:45.296626 [NOTICE] switch_channel.c:776 New Channel sofia/internal/1000@192.168.0.120 [d64d747e-8302-11df-91f3-1d2722671cfc] Occasionally, it works, it happens only the first time I am loading the module after machine was rebooted, when I restart freeswitch after that and try to load module again it always hangs, so I have to restart computer. When it hangs, freeswitch can not be shutdown with freeswitch -stop, I have to actually kill freeswitch process in order to restart it. After restarting freeswitch and Skype as well it follows the same bad scenario and hangs. I have to restart computer, and after that sometimes it works. Scripts to manage skype and freeswitch work perfect with version 1.0.4 pre8, I can restart freeswitch and skype multiple time no problem. I still have 1.0.4 pre8 sitting around and if I switch to it, it works perfectly. I am running recommended version of Skype 2.0.72 Everything is run on the 1Ghz machine (512MB RAM) Any thoughts what is wrong with my configuration? Anything I can try to resolve this issue? Thank you, Igor Below are samples of logs for "bad" and "good" loads. ----------------------------------- bad load ------------------------------------------- freeswitch@pbx> load mod_sk2010-06-27 20:40:52.462365 [WARNING] sofia.c:3700 Ping succeeded voipms with code 200 - count -1/1/1, state UP ypopen 2010-06-27 20:41:01.721128 [DEBUG] mod_skypopen.c:1223 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1223 ][none ][-1,-1,-1] globals.debug=8 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1230 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1230 ][none ][-1,-1,-1] globals.dialplan=XML 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1227 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1227 ][none ][-1,-1,-1] globals.context=default 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1233 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1233 ][none ][-1,-1,-1] globals.destination=5000 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1236 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1236 ][none ][-1,-1,-1] globals.skype_user=Boltik 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1239 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1239 ][none ][-1,-1,-1] globals.report_incoming_chatmessages=true 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1242 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1242 ][none ][-1,-1,-1] globals.silent_mode=false 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1245 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1245 ][none ][-1,-1,-1] globals.write_silence_when_idle=true 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1341 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1341 ][none ][-1,-1,-1] interface_id=1 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1352 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1352 ][none ][-1,-1,-1] name=Boltik 2010-06-27 20:41:01.726369 [DEBUG] mod_skypopen.c:1358 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1358 ][none ][-1,-1,-1] Initialized XInitThreads! 2010-06-27 20:41:01.727392 [DEBUG] mod_skypopen.c:1381 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1381 ][Boltik ][-1, 0, 0] CONFIGURING interface_id=1 2010-06-27 20:41:01.727392 [DEBUG] mod_skypopen.c:1418 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1418 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].name=Boltik 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1421 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1421 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].context=default 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1424 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1424 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].dialplan=XML 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1427 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1427 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].destination=5000 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1430 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1430 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].X11_display=:101 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1433 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1433 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].skype_user=Boltik 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1436 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1436 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].report_incoming_chatmessages=1 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1439 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1439 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].silent_mode=0 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1442 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1442 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].write_silence_when_idle=1 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1445 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1445 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].setsockopt=0 2010-06-27 20:41:01.729539 [WARNING] mod_skypopen.c:1447 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1447 ][Boltik ][-1, 0, 0] STARTING interface_id=1 2010-06-27 20:41:01.730932 [DEBUG] skypopen_protocol.c:1594 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1594 ][Boltik ][-1, 0, 0] X Display ':101' opened 2010-06-27 20:41:01.730932 [DEBUG] skypopen_protocol.c:1536 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1536 ][none ][-1,-1,-1] Skype instance found with id #2097368 2010-06-27 20:41:01.833832 [DEBUG] mod_skypopen.c:1150 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1150 ][Boltik ][-1, 0, 0] In skypopen_signaling_thread_func: started, p=0xb5bbaab8 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||OK||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 20:41:01.835975 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| 2010-06-27 20:41:01.936026 [NOTICE] mod_skypopen.c:1472 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1472 ][Boltik ][-1, 0, 0] WAITING roughly 10 seconds to find a running Skype client and connect to its SKYPE API for interface_id=1 2010-06-27 20:41:01.936026 [NOTICE] mod_skypopen.c:1481 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1481 ][Boltik ][-1, 0, 0] Found a running Skype client, connected to its SKYPE API for interface_id=1, waiting 60 seconds for CURRENTUSERHANDLE==Boltik 2010-06-27 20:41:01.936026 [WARNING] mod_skypopen.c:1500 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1500 ][Boltik ][-1, 0, 0] Interface_id=1 is now STARTED, the Skype client to which we are connected gave us the correct CURRENTUSERHANDLE (Boltik) 2010-06-27 20:41:01.937122 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||PROTOCOL 7|||| 2010-06-27 20:41:01.938438 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 20:41:02.006171 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 20:41:02.008363 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 20:41:02.008363 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 20:41:02.009489 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| ---------------------------------------------------------------------------------------- -------------------------------- good load --------------------------------------- freeswitch@pbx>load mod_skypopen 2010-06-27 22:48:30.592753 [DEBUG] mod_skypopen.c:1223 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1223 ][none ][-1,-1,-1] globals.debug=8 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1230 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1230 ][none ][-1,-1,-1] globals.dialplan=XML 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1227 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1227 ][none ][-1,-1,-1] globals.context=default 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1233 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1233 ][none ][-1,-1,-1] globals.destination=5000 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1236 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1236 ][none ][-1,-1,-1] globals.skype_user=Boltik 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1239 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1239 ][none ][-1,-1,-1] globals.report_incoming_chatmessages=true 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1242 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1242 ][none ][-1,-1,-1] globals.silent_mode=false 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1245 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1245 ][none ][-1,-1,-1] globals.write_silence_when_idle=true 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1341 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1341 ][none ][-1,-1,-1] interface_id=1 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1352 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1352 ][none ][-1,-1,-1] name=Boltik 2010-06-27 22:48:30.599763 [DEBUG] mod_skypopen.c:1358 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1358 ][none ][-1,-1,-1] Initialized XInitThreads! 2010-06-27 22:48:30.600769 [DEBUG] mod_skypopen.c:1381 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1381 ][Boltik ][-1, 0, 0] CONFIGURING interface_id=1 2010-06-27 22:48:30.600769 [DEBUG] mod_skypopen.c:1418 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1418 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].name=Boltik 2010-06-27 22:48:30.601785 [DEBUG] mod_skypopen.c:1421 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1421 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].context=default 2010-06-27 22:48:30.601785 [DEBUG] mod_skypopen.c:1424 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1424 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].dialplan=XML 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1427 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1427 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].destination=5000 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1430 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1430 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].X11_display=:101 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1433 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1433 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].skype_user=Boltik 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1436 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1436 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].report_incoming_chatmessages=1 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1439 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1439 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].silent_mode=0 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1442 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1442 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].write_silence_when_idle=1 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1445 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1445 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].setsockopt=0 2010-06-27 22:48:30.610668 [WARNING] mod_skypopen.c:1447 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1447 ][Boltik ][-1, 0, 0] STARTING interface_id=1 2010-06-27 22:48:30.612003 [DEBUG] skypopen_protocol.c:1594 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1594 ][Boltik ][-1, 0, 0] X Display ':101' opened 2010-06-27 22:48:30.613019 [DEBUG] skypopen_protocol.c:1536 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1536 ][none ][-1,-1,-1] Skype instance found with id #2097368 2010-06-27 22:48:30.716767 [DEBUG] mod_skypopen.c:1150 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1150 ][Boltik ][-1, 0, 0] In skypopen_signaling_thread_func: started, p=0xb5bb7ab8 2010-06-27 22:48:30.716767 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||OK||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS NA||| 2010-06-27 22:48:30.817902 [NOTICE] mod_skypopen.c:1472 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1472 ][Boltik ][-1, 0, 0] WAITING roughly 10 seconds to find a running Skype client and connect to its SKYPE API for interface_id=1 2010-06-27 22:48:30.817902 [NOTICE] mod_skypopen.c:1481 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1481 ][Boltik ][-1, 0, 0] Found a running Skype client, connected to its SKYPE API for interface_id=1, waiting 60 seconds for CURRENTUSERHANDLE==Boltik 2010-06-27 22:48:30.817902 [WARNING] mod_skypopen.c:1500 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1500 ][Boltik ][-1, 0, 0] Interface_id=1 is now STARTED, the Skype client to which we are connected gave us the correct CURRENTUSERHANDLE (Boltik) 2010-06-27 22:48:30.817902 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||PROTOCOL 7|||| 2010-06-27 22:48:30.817902 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 22:48:30.829711 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET AUTOAWAY OFF|||| 2010-06-27 22:48:30.829711 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||||| 2010-06-27 22:48:30.840990 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET WINDOWSTATE HIDDEN|||| 2010-06-27 22:48:30.840990 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||WINDOWSTATE HIDDEN||| 2010-06-27 22:48:30.897291 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 22:48:30.901487 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 22:48:30.901487 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 22:48:30.902499 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS NA||| 2010-06-27 22:48:30.903510 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||AUTOAWAY OFF||| 2010-06-27 22:48:30.905503 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USER Boltik ONLINESTATUS ONLINE||| 2010-06-27 22:48:30.906524 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| 2010-06-27 22:48:30.906524 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET USERSTATUS ONLINE|||| 2010-06-27 22:48:30.908668 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| +OK 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1544 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1544 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].interface_id=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1545 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1545 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].name=Boltik 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1546 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1546 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].context=default 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1547 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1547 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].dialplan=XML 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1548 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1548 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].destination=5000 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1549 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1549 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].X11_display=:101 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1550 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1550 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].skype_user=Boltik 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1552 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1552 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].report_incoming_chatmessages=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1553 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1553 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].silent_mode=0 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1554 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1554 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].write_silence_when_idle=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1555 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1555 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].setsockopt=0 2010-06-27 22:48:30.916757 [CONSOLE] switch_loadable_module.c:944 Successfully Loaded [mod_skypopen] 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:145 Adding Endpoint 'skypopen' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'sk' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'skypopen' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'skypopen_chat' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:378 Adding Chat interface 'skype' freeswitch@pbx> ----------------------------------------------------------------------------------------
On Tue, Jun 29, 2010 at 5:10 PM, Svetik <svetikvoip@gmail.com> wrote:
Hi,
On weekend I have upgraded to the latest Git tree from 1.0.4 pre8 which I was running for a long time, year may be. Everything went smooth, except Skype does not work anymore. Basically I followed Download &
Have you updated the configuration file? Maybe the configuration file format has changed from the one you are using... -giovanni
Installation Guide (http://wiki.freeswitch.org/wiki/Installation_Guide) and Mod skypopen Skype Endpoint and Trunk (http://wiki.freeswitch.org/wiki/Mod_skypopen_Skype_Endpoint_and_Trunk). The only difference, I have added <load module="mod_skypopen"/> at the end of the modules.conf.xml file to load skypopen module.
My impression is that mod_skypopen hangs during initialization and freeswitch or some of its parts are locked and do not respond properly. When I call any number, I am getting busy signal immediately after keying the number. Log file shows: 2010-06-28 18:58:40.334877 [CRIT] switch_core_session.c:1524 Throttle Error! 0 In my case freeswitch and skype are started automatically by the scripts that are basically recommended ones.
I have tried to load mod_skypopen manually (I removed mod_skypopen from the modules.conf.xml file) and most of the time it hangs.
1. I start the computer, freeswitch and skype autostart, but freeswitch does load mod_skypopen (it is not in the modules.conf.xml) 2. I shutdown freeswitch and start it from the console and wait for its prompt. 3. I do load mod_skypopen from the prompt. 4. I do load mod_skypopen from the prompt. 5. At this time it never returns to the prompt and there is no ringtone, log file shows: 2010-06-28 18:16:45.296626 [NOTICE] switch_channel.c:776 New Channel sofia/internal/1000@192.168.0.120 [d64d747e-8302-11df-91f3-1d2722671cfc]
Occasionally, it works, it happens only the first time I am loading the module after machine was rebooted, when I restart freeswitch after that and try to load module again it always hangs, so I have to restart computer.
When it hangs, freeswitch can not be shutdown with freeswitch -stop, I have to actually kill freeswitch process in order to restart it. After restarting freeswitch and Skype as well it follows the same bad scenario and hangs. I have to restart computer, and after that sometimes it works.
Scripts to manage skype and freeswitch work perfect with version 1.0.4 pre8, I can restart freeswitch and skype multiple time no problem. I still have 1.0.4 pre8 sitting around and if I switch to it, it works perfectly.
I am running recommended version of Skype 2.0.72 Everything is run on the 1Ghz machine (512MB RAM)
Any thoughts what is wrong with my configuration? Anything I can try to resolve this issue?
Thank you,
Igor
Below are samples of logs for "bad" and "good" loads.
----------------------------------- bad load ------------------------------------------- freeswitch@pbx> load mod_sk2010-06-27 20:40:52.462365 [WARNING] sofia.c:3700 Ping succeeded voipms with code 200 - count -1/1/1, state UP ypopen 2010-06-27 20:41:01.721128 [DEBUG] mod_skypopen.c:1223 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1223 ][none ][-1,-1,-1] globals.debug=8 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1230 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1230 ][none ][-1,-1,-1] globals.dialplan=XML 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1227 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1227 ][none ][-1,-1,-1] globals.context=default 2010-06-27 20:41:01.724336 [DEBUG] mod_skypopen.c:1233 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1233 ][none ][-1,-1,-1] globals.destination=5000 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1236 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1236 ][none ][-1,-1,-1] globals.skype_user=Boltik 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1239 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1239 ][none ][-1,-1,-1] globals.report_incoming_chatmessages=true 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1242 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1242 ][none ][-1,-1,-1] globals.silent_mode=false 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1245 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1245 ][none ][-1,-1,-1] globals.write_silence_when_idle=true 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1341 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1341 ][none ][-1,-1,-1] interface_id=1 2010-06-27 20:41:01.725354 [DEBUG] mod_skypopen.c:1352 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1352 ][none ][-1,-1,-1] name=Boltik 2010-06-27 20:41:01.726369 [DEBUG] mod_skypopen.c:1358 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1358 ][none ][-1,-1,-1] Initialized XInitThreads! 2010-06-27 20:41:01.727392 [DEBUG] mod_skypopen.c:1381 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1381 ][Boltik ][-1, 0, 0] CONFIGURING interface_id=1 2010-06-27 20:41:01.727392 [DEBUG] mod_skypopen.c:1418 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1418 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].name=Boltik 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1421 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1421 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].context=default 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1424 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1424 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].dialplan=XML 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1427 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1427 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].destination=5000 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1430 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1430 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].X11_display=:101 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1433 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1433 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].skype_user=Boltik 2010-06-27 20:41:01.728480 [DEBUG] mod_skypopen.c:1436 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1436 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].report_incoming_chatmessages=1 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1439 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1439 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].silent_mode=0 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1442 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1442 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].write_silence_when_idle=1 2010-06-27 20:41:01.729539 [DEBUG] mod_skypopen.c:1445 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1445 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].setsockopt=0 2010-06-27 20:41:01.729539 [WARNING] mod_skypopen.c:1447 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1447 ][Boltik ][-1, 0, 0] STARTING interface_id=1 2010-06-27 20:41:01.730932 [DEBUG] skypopen_protocol.c:1594 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1594 ][Boltik ][-1, 0, 0] X Display ':101' opened 2010-06-27 20:41:01.730932 [DEBUG] skypopen_protocol.c:1536 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1536 ][none ][-1,-1,-1] Skype instance found with id #2097368 2010-06-27 20:41:01.833832 [DEBUG] mod_skypopen.c:1150 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1150 ][Boltik ][-1, 0, 0] In skypopen_signaling_thread_func: started, p=0xb5bbaab8 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||OK||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 20:41:01.834965 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 20:41:01.835975 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| 2010-06-27 20:41:01.936026 [NOTICE] mod_skypopen.c:1472 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1472 ][Boltik ][-1, 0, 0] WAITING roughly 10 seconds to find a running Skype client and connect to its SKYPE API for interface_id=1 2010-06-27 20:41:01.936026 [NOTICE] mod_skypopen.c:1481 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1481 ][Boltik ][-1, 0, 0] Found a running Skype client, connected to its SKYPE API for interface_id=1, waiting 60 seconds for CURRENTUSERHANDLE==Boltik 2010-06-27 20:41:01.936026 [WARNING] mod_skypopen.c:1500 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1500 ][Boltik ][-1, 0, 0] Interface_id=1 is now STARTED, the Skype client to which we are connected gave us the correct CURRENTUSERHANDLE (Boltik) 2010-06-27 20:41:01.937122 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||PROTOCOL 7|||| 2010-06-27 20:41:01.938438 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 20:41:02.006171 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 20:41:02.008363 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 20:41:02.008363 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 20:41:02.009489 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE|||
----------------------------------------------------------------------------------------
-------------------------------- good load --------------------------------------- freeswitch@pbx>load mod_skypopen 2010-06-27 22:48:30.592753 [DEBUG] mod_skypopen.c:1223 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1223 ][none ][-1,-1,-1] globals.debug=8 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1230 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1230 ][none ][-1,-1,-1] globals.dialplan=XML 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1227 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1227 ][none ][-1,-1,-1] globals.context=default 2010-06-27 22:48:30.597741 [DEBUG] mod_skypopen.c:1233 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1233 ][none ][-1,-1,-1] globals.destination=5000 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1236 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1236 ][none ][-1,-1,-1] globals.skype_user=Boltik 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1239 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1239 ][none ][-1,-1,-1] globals.report_incoming_chatmessages=true 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1242 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1242 ][none ][-1,-1,-1] globals.silent_mode=false 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1245 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1245 ][none ][-1,-1,-1] globals.write_silence_when_idle=true 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1341 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1341 ][none ][-1,-1,-1] interface_id=1 2010-06-27 22:48:30.598753 [DEBUG] mod_skypopen.c:1352 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1352 ][none ][-1,-1,-1] name=Boltik 2010-06-27 22:48:30.599763 [DEBUG] mod_skypopen.c:1358 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1358 ][none ][-1,-1,-1] Initialized XInitThreads! 2010-06-27 22:48:30.600769 [DEBUG] mod_skypopen.c:1381 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1381 ][Boltik ][-1, 0, 0] CONFIGURING interface_id=1 2010-06-27 22:48:30.600769 [DEBUG] mod_skypopen.c:1418 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1418 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].name=Boltik 2010-06-27 22:48:30.601785 [DEBUG] mod_skypopen.c:1421 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1421 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].context=default 2010-06-27 22:48:30.601785 [DEBUG] mod_skypopen.c:1424 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1424 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].dialplan=XML 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1427 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1427 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].destination=5000 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1430 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1430 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].X11_display=:101 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1433 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1433 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].skype_user=Boltik 2010-06-27 22:48:30.609658 [DEBUG] mod_skypopen.c:1436 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1436 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].report_incoming_chatmessages=1 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1439 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1439 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].silent_mode=0 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1442 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1442 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].write_silence_when_idle=1 2010-06-27 22:48:30.610668 [DEBUG] mod_skypopen.c:1445 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1445 ][Boltik ][-1, 0, 0] interface_id=1 globals.SKYPOPEN_INTERFACES[interface_id].setsockopt=0 2010-06-27 22:48:30.610668 [WARNING] mod_skypopen.c:1447 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1447 ][Boltik ][-1, 0, 0] STARTING interface_id=1 2010-06-27 22:48:30.612003 [DEBUG] skypopen_protocol.c:1594 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1594 ][Boltik ][-1, 0, 0] X Display ':101' opened 2010-06-27 22:48:30.613019 [DEBUG] skypopen_protocol.c:1536 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1536 ][none ][-1,-1,-1] Skype instance found with id #2097368 2010-06-27 22:48:30.716767 [DEBUG] mod_skypopen.c:1150 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1150 ][Boltik ][-1, 0, 0] In skypopen_signaling_thread_func: started, p=0xb5bb7ab8 2010-06-27 22:48:30.716767 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||OK||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 22:48:30.717802 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS NA||| 2010-06-27 22:48:30.817902 [NOTICE] mod_skypopen.c:1472 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1472 ][Boltik ][-1, 0, 0] WAITING roughly 10 seconds to find a running Skype client and connect to its SKYPE API for interface_id=1 2010-06-27 22:48:30.817902 [NOTICE] mod_skypopen.c:1481 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][NOTICA 1481 ][Boltik ][-1, 0, 0] Found a running Skype client, connected to its SKYPE API for interface_id=1, waiting 60 seconds for CURRENTUSERHANDLE==Boltik 2010-06-27 22:48:30.817902 [WARNING] mod_skypopen.c:1500 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][WARNINGA 1500 ][Boltik ][-1, 0, 0] Interface_id=1 is now STARTED, the Skype client to which we are connected gave us the correct CURRENTUSERHANDLE (Boltik) 2010-06-27 22:48:30.817902 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||PROTOCOL 7|||| 2010-06-27 22:48:30.817902 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||PROTOCOL 7||| 2010-06-27 22:48:30.829711 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET AUTOAWAY OFF|||| 2010-06-27 22:48:30.829711 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||||| 2010-06-27 22:48:30.840990 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET WINDOWSTATE HIDDEN|||| 2010-06-27 22:48:30.840990 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||WINDOWSTATE HIDDEN||| 2010-06-27 22:48:30.897291 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CONNSTATUS ONLINE||| 2010-06-27 22:48:30.901487 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||CURRENTUSERHANDLE Boltik||| 2010-06-27 22:48:30.901487 [DEBUG] skypopen_protocol.c:263 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 263 ][Boltik ][-1, 0, 0] Skype MSG: message: CURRENTUSERHANDLE, currentuserhandle: CURRENTUSERHANDLE, cuh: Boltik, skype_user: Boltik! 2010-06-27 22:48:30.902499 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS NA||| 2010-06-27 22:48:30.903510 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||AUTOAWAY OFF||| 2010-06-27 22:48:30.905503 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USER Boltik ONLINESTATUS ONLINE||| 2010-06-27 22:48:30.906524 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE||| 2010-06-27 22:48:30.906524 [DEBUG] skypopen_protocol.c:1494 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1494 ][Boltik ][-1, 0, 0] SENDING: |||SET USERSTATUS ONLINE|||| 2010-06-27 22:48:30.908668 [DEBUG] skypopen_protocol.c:176 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 176 ][Boltik ][-1, 0, 0] READING: |||USERSTATUS ONLINE|||
+OK
2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1544 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1544 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].interface_id=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1545 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1545 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].name=Boltik 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1546 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1546 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].context=default 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1547 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1547 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].dialplan=XML 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1548 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1548 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].destination=5000 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1549 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1549 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].X11_display=:101 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1550 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1550 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].skype_user=Boltik 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1552 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1552 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].report_incoming_chatmessages=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1553 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1553 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].silent_mode=0 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1554 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1554 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].write_silence_when_idle=1 2010-06-27 22:48:30.916757 [DEBUG] mod_skypopen.c:1555 rev git2svn-syncpoint-master-121-g4e82098[(nil)|37 ][DEBUG_SKYPE 1555 ][Boltik ][-1, 0, 0] i=1 globals.SKYPOPEN_INTERFACES[1].setsockopt=0 2010-06-27 22:48:30.916757 [CONSOLE] switch_loadable_module.c:944 Successfully Loaded [mod_skypopen] 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:145 Adding Endpoint 'skypopen' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'sk' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'skypopen' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:273 Adding API Function 'skypopen_chat' 2010-06-27 22:48:30.916757 [NOTICE] switch_loadable_module.c:378 Adding Chat interface 'skype' freeswitch@pbx>
----------------------------------------------------------------------------------------
_______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Sincerely, Giovanni Maruzzelli Cell : +39-347-2665618
Giovanni, Thank you for your reply. I did not use my original configuration file, I have migrated changes into new configuration file. That is how it looks like: <configuration name="skypopen.conf" description="Skypopen Configuration"> <global_settings> <param name="debug" value="8"/> <param name="dialplan" value="XML"/> <param name="context" value="default"/> <param name="destination" value="5000"/> <param name="skype_user" value="Boltik"/> <param name="report_incoming_chatmessages" value="true"/> <param name="silent_mode" value="false"/> <param name="write_silence_when_idle" value="true"/> </global_settings> <!-- one entry here per each skypopen interface --> <per_interface_settings> <interface id="1" name="Boltik"> <param name="X11-display" value=":101"/> <param name="skype_user" value="Boltik"/> </interface> <!-- following interfaces are commented out <interface id="2" name="interface2"> <param name="X11-display" value=":102"/> <param name="skype_user" value="user2"/> </interface> <interface id="3" name="interface3"> <param name="X11-display" value=":103"/> <param name="skype_user" value="user3"/> </interface> <interface id="4" name="interface4"> <param name="X11-display" value=":104"/> <param name="skype_user" value="user4"/> </interface> --> </per_interface_settings> </configuration> I have tried to use old configuration file from skypiax module, but it gives me the same problem. Any thoughts? Can I try something that helps to diagnose this problem? Igor
On Tue, Jun 29, 2010 at 5:10 PM, Svetik<svetikvoip at gmail.com <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users>> wrote: / Hi, />>/ />>/ On weekend I have upgraded to the latest Git tree from 1.0.4 pre8 which />>/ I was running for a long time, year may be. Everything went smooth, />>/ except Skype does not work anymore. Basically I followed Download& /
Have you updated the configuration file? Maybe the configuration file format has changed from the one you are using...
-giovanni
On Fri, Jul 2, 2010 at 4:17 PM, Svetik <svetikvoip@gmail.com> wrote:
I did not use my original configuration file, I have migrated changes into new configuration file. That is how it looks like:
Thanks a lot for the infos, Svetik There has been other reports on a possible malfunctioning of mod_skypopen with recent FS code. I'm traveling right now, but I'll try to sort it out beginning next week. Thanks again for reporting, and please open a Jira adding all the information you can. -giovanni
<configuration name="skypopen.conf" description="Skypopen Configuration"> <global_settings> <param name="debug" value="8"/> <param name="dialplan" value="XML"/> <param name="context" value="default"/> <param name="destination" value="5000"/> <param name="skype_user" value="Boltik"/> <param name="report_incoming_chatmessages" value="true"/> <param name="silent_mode" value="false"/> <param name="write_silence_when_idle" value="true"/> </global_settings> <!-- one entry here per each skypopen interface --> <per_interface_settings> <interface id="1" name="Boltik"> <param name="X11-display" value=":101"/> <param name="skype_user" value="Boltik"/> </interface> <!-- following interfaces are commented out <interface id="2" name="interface2"> <param name="X11-display" value=":102"/> <param name="skype_user" value="user2"/> </interface>
<interface id="3" name="interface3"> <param name="X11-display" value=":103"/> <param name="skype_user" value="user3"/> </interface> <interface id="4" name="interface4"> <param name="X11-display" value=":104"/> <param name="skype_user" value="user4"/> </interface> --> </per_interface_settings> </configuration>
I have tried to use old configuration file from skypiax module, but it gives me the same problem.
Any thoughts? Can I try something that helps to diagnose this problem?
Igor
On Tue, Jun 29, 2010 at 5:10 PM, Svetik<svetikvoip at gmail.com <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users>> wrote: / Hi, />>/ />>/ On weekend I have upgraded to the latest Git tree from 1.0.4 pre8 which />>/ I was running for a long time, year may be. Everything went smooth, />>/ except Skype does not work anymore. Basically I followed Download& /
Have you updated the configuration file? Maybe the configuration file format has changed from the one you are using...
-giovanni
_______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Sincerely, Giovanni Maruzzelli Cell : +39-347-2665618
participants (2)
-
Giovanni Maruzzelli
-
Svetik