March 2011 - FreeSWITCH-users - FreeSWITCH Mailing Lists

tls/srtp goes straight to voicemail
by Mitch Johnson 08 Mar '11

08 Mar '11

I'm trying to setup a secure phone call across two eyebeam clients running on Apple iPhones. I am pretty new to Freeswitch. The problem is that when I make a call using SRTP between the two phones it goes straight to voicemail. I took some snippets of the debug. Any help would be greatly appreciated. 2011-03-05 23:21:08.502797 [DEBUG] sofia.c:6451 IP 192.168.60.130 Rejected by acl "domains". Falling back to Digest auth. 2011-03-05 23:21:08.502797 [WARNING] sofia_reg.c:1246 SIP auth challenge (INVITE) on sofia profile 'internal' for [1005(a)172.16.200.60] from ip 192.168.60.130 2011-03-05 23:21:08.527274 [DEBUG] sofia.c:6451 IP 192.168.60.130 Rejected by acl "domains". Falling back to Digest auth. 2011-03-05 23:21:08.527274 [NOTICE] switch_channel.c:812 New Channel sofia/internal/1006(a)172.16.200.60 [d9976fea-1e9e-4d77-8c26-fa3bd32c4979] 2011-03-05 23:21:08.527274 [DEBUG] sofia.c:4683 Channel sofia/internal/1006(a)172.16.200.60 entering state [received][100] 2011-03-05 23:21:08.527274 [DEBUG] sofia.c:4694 Remote SDP: v=0 o=- 3508374466 3508374466 IN IP4 192.168.60.130 s=cpc_med c=IN IP4 192.168.60.130 t=0 0 a=X-nat:0 m=audio 4004 RTP/SAVP 0 8 104 3 96 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 iLBC/8000 a=fmtp:104 mode=30 a=rtpmap:3 GSM/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:znq1mGYq9Dz+5G2GY3tKNJ0gTMGfvBNeeIYKuIKZ a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:iRs0wouqlzmT6T06/K3CS9HUJmwnwkP+w1rhaDre 2011-03-05 23:21:08.527274 [DEBUG] sofia_glue.c:4415 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:znq1mGYq9Dz+5G2GY3tKNJ0gTMGfvBNeeIYKuIKZ] 2011-03-05 23:21:08.527274 [DEBUG] sofia_glue.c:2821 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:zXMFICKxSVhKLofieTRxrWH5I35i/Bu6lkit2BbE] ...... 2011-03-05 23:21:08.535483 [DEBUG] switch_core_state_machine.c:157 sofia/internal/1006(a)172.16.200.60 Standard EXECUTE EXECUTE sofia/internal/1006(a)172.16.200.60 set(sip_secure_media=true) 2011-03-05 23:21:08.535483 [DEBUG] mod_dptools.c:1059 sofia/internal/1006(a)172.16.200.60 SET [sip_secure_media]=[true] EXECUTE sofia/internal/1006(a)172.16.200.60 export(sip_secure_media=true) 2011-03-05 23:21:08.536478 [DEBUG] switch_channel.c:961 EXPORT (export_vars) [sip_secure_media]=[true] EXECUTE sofia/internal/1006(a)172.16.200.60 hash(insert/172.16.200.60-spymap/1006/d9976fea-1e9e-4d77-8c26-fa3bd32c4979) EXECUTE sofia/internal/1006(a)172.16.200.60 hash(insert/172.16.200.60-last_dial/1006/1005) EXECUTE sofia/internal/1006(a)172.16.200.60 hash(insert/172.16.200.60-last_dial/global/d9976fea-1e9e-4d77-8c26-fa3bd32c4979) EXECUTE sofia/internal/1006(a)172.16.200.60 set(RFC2822_DATE=Sat, 05 Mar 2011 23:21:08 -0500) 2011-03-05 23:21:08.537477 [DEBUG] mod_dptools.c:1059 sofia/internal/1006(a)172.16.200.60 SET [RFC2822_DATE]=[Sat, 05 Mar 2011 23:21:08 -0500] EXECUTE sofia/internal/1006(a)172.16.200.60 set(dialed_extension=1005) 2011-03-05 23:21:08.538480 [DEBUG] mod_dptools.c:1059 sofia/internal/1006(a)172.16.200.60 SET [dialed_extension]=[1005] EXECUTE sofia/internal/1006(a)172.16.200.60 export(dialed_extension=1005) 2011-03-05 23:21:08.538480 [DEBUG] switch_channel.c:961 EXPORT (export_vars) [dialed_extension]=[1005] EXECUTE sofia/internal/1006(a)172.16.200.60 bind_meta_app(1 b s execute_extension::dx XML features) 2011-03-05 23:21:08.539474 [INFO] switch_ivr_async.c:3013 Bound B-Leg: *1 execute_extension::dx XML features EXECUTE sofia/internal/1006(a)172.16.200.60 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1006.2011-03-05-23-21-08.wav) ..... EXECUTE sofia/internal/1006(a)172.16.200.60 hash(insert/172.16.200.60-last_dial/techsupport/d9976fea-1e9e-4d77-8c26-fa3bd32c4979) EXECUTE sofia/internal/1006(a)172.16.200.60 bridge(user/1005(a)172.16.200.60) 2011-03-05 23:21:08.556511 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [sip_secure_media]=[true] to event 2011-03-05 23:21:08.556511 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [dialed_extension]=[1005] to event 2011-03-05 23:21:08.558529 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [sip_secure_media]=[true] to event 2011-03-05 23:21:08.558529 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [dialed_extension]=[1005] to event 2011-03-05 23:21:08.558529 [DEBUG] switch_ivr_originate.c:1971 variable string 0 = [sip_secure_media=false] 2011-03-05 23:21:08.558529 [DEBUG] switch_ivr_originate.c:1971 variable string 1 = [presence_id=1005(a)172.16.200.60] 2011-03-05 23:21:08.558529 [NOTICE] switch_channel.c:812 New Channel sofia/internal/sip:1005@192.168.60.135:53664 [aed041ea-4355-46aa-ad11-e58389f8e6d9] 2011-03-05 23:21:08.558529 [DEBUG] mod_sofia.c:4151 (sofia/internal/sip:1005@192.168.60.135:53664) State Change CS_NEW -> CS_INIT 2011-03-05 23:21:08.558529 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.565722 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_INIT 2011-03-05 23:21:08.566543 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/sip:1005@192.168.60.135:53664) State INIT 2011-03-05 23:21:08.566543 [DEBUG] mod_sofia.c:84 sofia/internal/sip:1005@192.168.60.135:53664 SOFIA INIT 2011-03-05 23:21:08.570553 [DEBUG] sofia_glue.c:2327 sip:1005@192.168.60.135:53710;transport=TLS Setting proxy route to sofia/internal/sip:1005@192.168.60.135:53664 2011-03-05 23:21:08.572076 [DEBUG] mod_sofia.c:124 (sofia/internal/sip:1005@192.168.60.135:53664) State Change CS_INIT -> CS_ROUTING 2011-03-05 23:21:08.572076 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.572076 [DEBUG] sofia.c:4683 Channel sofia/internal/sip:1005@192.168.60.135:53664 entering state [calling][0] 2011-03-05 23:21:08.572076 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/sip:1005@192.168.60.135:53664) State INIT going to sleep 2011-03-05 23:21:08.572076 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_ROUTING 2011-03-05 23:21:08.572076 [DEBUG] switch_channel.c:1664 (sofia/internal/sip:1005@192.168.60.135:53664) Callstate Change DOWN -> RINGING 2011-03-05 23:21:08.573709 [DEBUG] switch_core_state_machine.c:359 (sofia/internal/sip:1005@192.168.60.135:53664) State ROUTING 2011-03-05 23:21:08.573709 [DEBUG] mod_sofia.c:147 sofia/internal/sip:1005@192.168.60.135:53664 SOFIA ROUTING 2011-03-05 23:21:08.574575 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/sip:1005@192.168.60.135:53664) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-03-05 23:21:08.574575 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.574575 [DEBUG] switch_core_state_machine.c:359 (sofia/internal/sip:1005@192.168.60.135:53664) State ROUTING going to sleep 2011-03-05 23:21:08.574575 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_CONSUME_MEDIA 2011-03-05 23:21:08.574575 [DEBUG] switch_core_state_machine.c:378 (sofia/internal/sip:1005@192.168.60.135:53664) State CONSUME_MEDIA 2011-03-05 23:21:08.574575 [DEBUG] switch_core_state_machine.c:378 (sofia/internal/sip:1005@192.168.60.135:53664) State CONSUME_MEDIA going to sleep 2011-03-05 23:21:08.650486 [DEBUG] sofia.c:4683 Channel sofia/internal/sip:1005@192.168.60.135:53664 entering state [terminated][406] 2011-03-05 23:21:08.650486 [DEBUG] switch_channel.c:2545 (sofia/internal/sip:1005@192.168.60.135:53664) Callstate Change RINGING -> HANGUP 2011-03-05 23:21:08.650486 [NOTICE] sofia.c:5323 Hangup sofia/internal/sip:1005@192.168.60.135:53664 [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED] 2011-03-05 23:21:08.650486 [DEBUG] switch_channel.c:2561 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [KILL] 2011-03-05 23:21:08.650486 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.652479 [DEBUG] switch_ivr_originate.c:3502 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] 2011-03-05 23:21:08.652479 [ERR] switch_ivr_originate.c:2638 Cannot create outgoing channel of type [user] cause: [SERVICE_NOT_IMPLEMENTED] 2011-03-05 23:21:08.652479 [DEBUG] switch_ivr_originate.c:3502 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] 2011-03-05 23:21:08.653662 [INFO] mod_dptools.c:2623 Originate Failed. Cause: SERVICE_NOT_IMPLEMENTED EXECUTE sofia/internal/1006(a)172.16.200.60 answer() 2011-03-05 23:21:08.655518 [DEBUG] sofia_glue.c:2990 AUDIO RTP [sofia/internal/1006(a)172.16.200.60] 172.16.200.60 port 22870 -> 192.168.60.130 port 4004 codec: 0 ms: 20 2011-03-05 23:21:08.656829 [DEBUG] switch_rtp.c:1621 Starting timer [soft] 160 bytes per 20ms 2011-03-05 23:21:08.658299 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_HANGUP 2011-03-05 23:21:08.658299 [DEBUG] sofia_glue.c:3235 Set 2833 dtmf send payload to 96 2011-03-05 23:21:08.658299 [DEBUG] sofia_glue.c:3240 Set 2833 dtmf receive payload to 96 2011-03-05 23:21:08.658299 [INFO] switch_rtp.c:1450 Activating Secure RTP SEND 2011-03-05 23:21:08.659547 [DEBUG] switch_core_sqldb.c:1422 Secure Type: srtp:AES_CM_128_HMAC_SHA1_80 2011-03-05 23:21:08.659547 [INFO] switch_rtp.c:1430 Activating Secure RTP RECV 2011-03-05 23:21:08.659547 [DEBUG] switch_core_sqldb.c:1422 Secure Type: srtp:AES_CM_128_HMAC_SHA1_80 2011-03-05 23:21:08.659547 [DEBUG] mod_sofia.c:681 Local SDP sofia/internal/1006(a)172.16.200.60: v=0 o=FreeSWITCH 1299362398 1299362399 IN IP4 172.16.200.60 s=FreeSWITCH c=IN IP4 172.16.200.60 t=0 0 m=audio 22870 RTP/SAVP 0 96 a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zXMFICKxSVhKLofieTRxrWH5I35i/Bu6lkit2BbE 2011-03-05 23:21:08.661306 [DEBUG] sofia.c:4683 Channel sofia/internal/1006(a)172.16.200.60 entering state [completed][200] 2011-03-05 23:21:08.661306 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:08.661306 [DEBUG] switch_channel.c:2795 (sofia/internal/1006(a)172.16.200.60) Callstate Change RINGING -> ACTIVE 2011-03-05 23:21:08.662543 [NOTICE] mod_dptools.c:929 Channel [sofia/internal/1006(a)172.16.200.60] has been answered EXECUTE sofia/internal/1006(a)172.16.200.60 sleep(1000) 2011-03-05 23:21:08.664527 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:1005@192.168.60.135:53664) State HANGUP 2011-03-05 23:21:08.665590 [DEBUG] mod_sofia.c:451 sofia/internal/sip:1005@192.168.60.135:53664 Overriding SIP cause 501 with 406 from the other leg 2011-03-05 23:21:08.665590 [DEBUG] mod_sofia.c:457 Channel sofia/internal/sip:1005@192.168.60.135:53664 hanging up, cause: SERVICE_NOT_IMPLEMENTED 2011-03-05 23:21:08.666556 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:1005@192.168.60.135:53664 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED 2011-03-05 23:21:08.666556 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:1005@192.168.60.135:53664) State HANGUP going to sleep 2011-03-05 23:21:08.666556 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:1005@192.168.60.135:53664) State Change CS_HANGUP -> CS_REPORTING 2011-03-05 23:21:08.666556 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.666556 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_REPORTING 2011-03-05 23:21:08.667827 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:1005@192.168.60.135:53664) State REPORTING 2011-03-05 23:21:08.667827 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:1005@192.168.60.135:53664 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED 2011-03-05 23:21:08.667827 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:1005@192.168.60.135:53664) State REPORTING going to sleep 2011-03-05 23:21:08.669333 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/sip:1005@192.168.60.135:53664) State Change CS_REPORTING -> CS_DESTROY 2011-03-05 23:21:08.669333 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:1005@192.168.60.135:53664 [BREAK] 2011-03-05 23:21:08.669333 [DEBUG] switch_core_session.c:1288 Session 162 (sofia/internal/sip:1005@192.168.60.135:53664) Locked, Waiting on external entities 2011-03-05 23:21:08.669333 [NOTICE] switch_core_session.c:1306 Session 162 (sofia/internal/sip:1005@192.168.60.135:53664) Ended 2011-03-05 23:21:08.669333 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/sip:1005@192.168.60.135:53664 [CS_DESTROY] 2011-03-05 23:21:08.670544 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/sip:1005@192.168.60.135:53664) Callstate Change HANGUP -> DOWN 2011-03-05 23:21:08.670544 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:1005@192.168.60.135:53664) Running State Change CS_DESTROY 2011-03-05 23:21:08.671619 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:1005@192.168.60.135:53664) State DESTROY 2011-03-05 23:21:08.671619 [DEBUG] mod_sofia.c:362 sofia/internal/sip:1005@192.168.60.135:53664 SOFIA DESTROY 2011-03-05 23:21:08.671619 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:1005@192.168.60.135:53664 Standard DESTROY 2011-03-05 23:21:08.671619 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:1005@192.168.60.135:53664) State DESTROY going to sleep 2011-03-05 23:21:08.742675 [DEBUG] switch_rtp.c:2989 Correct ip/port confirmed. 2011-03-05 23:21:08.742675 [DEBUG] sofia.c:4683 Channel sofia/internal/1006(a)172.16.200.60 entering state [ready][200] EXECUTE sofia/internal/1006(a)172.16.200.60 bridge(loopback/app=voicemail:default 172.16.200.60 1005) 2011-03-05 23:21:09.682417 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [sip_secure_media]=[true] to event 2011-03-05 23:21:09.682417 [DEBUG] switch_channel.c:918 sofia/internal/1006(a)172.16.200.60 EXPORTING[export_vars] [dialed_extension]=[1005] to event 2011-03-05 23:21:09.682417 [NOTICE] switch_channel.c:812 New Channel loopback/app=voicemail:default 172.16.200.60 1005-a [f2016cad-3669-4b54-95c2-7f3a958ddca0] 2011-03-05 23:21:09.682417 [DEBUG] mod_loopback.c:131 loopback/app=voicemail:default 172.16.200.60 1005-a setup codec PCMU/8000/20 2011-03-05 23:21:09.682417 [NOTICE] switch_channel.c:810 Rename Channel loopback/app=voicemail:default 172.16.200.60 1005-a->loopback/voicemail-a [f2016cad-3669-4b54-95c2-7f3a958ddca0] 2011-03-05 23:21:09.682417 [DEBUG] mod_loopback.c:939 (loopback/voicemail-a) State Change CS_NEW -> CS_INIT 2011-03-05 23:21:09.682417 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:09.682417 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:09.687524 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_INIT 2011-03-05 23:21:09.687524 [DEBUG] switch_core_state_machine.c:356 (loopback/voicemail-a) State INIT 2011-03-05 23:21:09.687524 [NOTICE] switch_channel.c:812 New Channel loopback/voicemail-b [23eaf856-1bf2-4a84-94cc-e71178199468] 2011-03-05 23:21:09.688656 [DEBUG] mod_loopback.c:131 loopback/voicemail-b setup codec PCMU/8000/20 2011-03-05 23:21:09.689557 [DEBUG] mod_loopback.c:244 (loopback/voicemail-b) State Change CS_NEW -> CS_INIT 2011-03-05 23:21:09.689557 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-b [BREAK] 2011-03-05 23:21:09.689557 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:09.690598 [DEBUG] mod_loopback.c:290 (loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING 2011-03-05 23:21:09.690598 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:09.690598 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:09.690598 [DEBUG] switch_core_state_machine.c:356 (loopback/voicemail-a) State INIT going to sleep 2011-03-05 23:21:09.690598 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_ROUTING 2011-03-05 23:21:09.690598 [DEBUG] switch_channel.c:1664 (loopback/voicemail-a) Callstate Change DOWN -> RINGING 2011-03-05 23:21:09.691794 [DEBUG] switch_core_state_machine.c:359 (loopback/voicemail-a) State ROUTING 2011-03-05 23:21:09.691794 [DEBUG] mod_loopback.c:324 loopback/voicemail-a CHANNEL ROUTING 2011-03-05 23:21:09.691794 [DEBUG] switch_ivr_originate.c:66 (loopback/voicemail-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-03-05 23:21:09.691794 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:09.691794 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:09.691794 [DEBUG] switch_core_state_machine.c:359 (loopback/voicemail-a) State ROUTING going to sleep 2011-03-05 23:21:09.691794 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA 2011-03-05 23:21:09.691794 [DEBUG] switch_core_state_machine.c:378 (loopback/voicemail-a) State CONSUME_MEDIA 2011-03-05 23:21:09.691794 [DEBUG] mod_loopback.c:529 CHANNEL CONSUME_MEDIA 2011-03-05 23:21:09.693484 [DEBUG] switch_core_state_machine.c:378 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep 2011-03-05 23:21:09.693484 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-b) Running State Change CS_INIT 2011-03-05 23:21:09.693484 [DEBUG] switch_core_state_machine.c:356 (loopback/voicemail-b) State INIT 2011-03-05 23:21:09.693484 [DEBUG] mod_loopback.c:290 (loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING 2011-03-05 23:21:09.693484 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-b [BREAK] 2011-03-05 23:21:09.693484 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:09.693484 [DEBUG] switch_core_state_machine.c:356 (loopback/voicemail-b) State INIT going to sleep 2011-03-05 23:21:09.693484 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-b) Running State Change CS_ROUTING 2011-03-05 23:21:09.693484 [DEBUG] switch_channel.c:1664 (loopback/voicemail-b) Callstate Change DOWN -> RINGING 2011-03-05 23:21:09.695557 [DEBUG] switch_core_state_machine.c:359 (loopback/voicemail-b) State ROUTING 2011-03-05 23:21:09.695557 [DEBUG] mod_loopback.c:324 loopback/voicemail-b CHANNEL ROUTING 2011-03-05 23:21:09.695557 [DEBUG] mod_loopback.c:343 (loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE 2011-03-05 23:21:09.695557 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-b [BREAK] 2011-03-05 23:21:09.695557 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:09.695557 [DEBUG] switch_core_state_machine.c:359 (loopback/voicemail-b) State ROUTING going to sleep 2011-03-05 23:21:09.695557 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-b) Running State Change CS_EXECUTE 2011-03-05 23:21:09.695557 [DEBUG] switch_core_state_machine.c:366 (loopback/voicemail-b) State EXECUTE 2011-03-05 23:21:09.695557 [DEBUG] mod_loopback.c:363 loopback/voicemail-b CHANNEL EXECUTE 2011-03-05 23:21:09.696643 [DEBUG] switch_core_state_machine.c:157 loopback/voicemail-b Standard EXECUTE EXECUTE loopback/voicemail-b pre_answer() 2011-03-05 23:21:09.696643 [NOTICE] mod_loopback.c:722 Pre-Answer loopback/voicemail-a! 2011-03-05 23:21:09.696643 [DEBUG] switch_channel.c:2638 (loopback/voicemail-a) Callstate Change RINGING -> EARLY 2011-03-05 23:21:09.697671 [DEBUG] switch_channel.c:2689 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:09.698642 [DEBUG] switch_core_session.c:709 Send signal loopback/voicemail-b [BREAK] 2011-03-05 23:21:09.698642 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:09.698642 [NOTICE] mod_dptools.c:955 Pre-Answer loopback/voicemail-b! 2011-03-05 23:21:09.698642 [DEBUG] switch_channel.c:2638 (loopback/voicemail-b) Callstate Change RINGING -> EARLY 2011-03-05 23:21:09.700393 [DEBUG] switch_channel.c:2689 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] EXECUTE loopback/voicemail-b voicemail(default 172.16.200.60 1005) 2011-03-05 23:21:09.701645 [DEBUG] switch_ivr_originate.c:3408 Originate Resulted in Success: [loopback/voicemail-a] 2011-03-05 23:21:09.701645 [DEBUG] switch_core_session.c:709 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:09.701645 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:09.701645 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:09.701645 [DEBUG] switch_ivr_bridge.c:1234 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2011-03-05 23:21:09.701645 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:09.701645 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:09.703337 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA 2011-03-05 23:21:09.703337 [DEBUG] switch_core_state_machine.c:369 (loopback/voicemail-a) State EXCHANGE_MEDIA 2011-03-05 23:21:09.703337 [DEBUG] mod_loopback.c:491 CHANNEL LOOPBACK 2011-03-05 23:21:09.862580 [DEBUG] switch_ivr_play_say.c:63 No language specified - Using [en] 2011-03-05 23:21:09.874639 [DEBUG] switch_ivr_play_say.c:244 Handle play-file:[voicemail/vm-person.wav] (en:en) 2011-03-05 23:21:09.875636 [DEBUG] switch_ivr_play_say.c:1291 Codec Activated L16@8000hz 1 channels 20ms 2011-03-05 23:21:11.262410 [DEBUG] switch_ivr_play_say.c:1635 done playing file 2011-03-05 23:21:11.362431 [DEBUG] switch_ivr_play_say.c:244 Handle say:[1005] (en:en) 2011-03-05 23:21:11.362431 [DEBUG] switch_ivr_play_say.c:1291 Codec Activated L16@8000hz 1 channels 20ms 2011-03-05 23:21:11.370005 [DEBUG] switch_channel.c:2545 (sofia/internal/1006(a)172.16.200.60) Callstate Change ACTIVE -> HANGUP 2011-03-05 23:21:11.370005 [NOTICE] sofia.c:537 Hangup sofia/internal/1006(a)172.16.200.60 [CS_EXECUTE] [NORMAL_CLEARING] 2011-03-05 23:21:11.370005 [DEBUG] switch_channel.c:2561 Send signal sofia/internal/1006(a)172.16.200.60 [KILL] 2011-03-05 23:21:11.370005 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] switch_ivr_bridge.c:500 sofia/internal/1006(a)172.16.200.60 ending bridge by request from read function 2011-03-05 23:21:11.381511 [DEBUG] switch_ivr_bridge.c:581 BRIDGE THREAD DONE [sofia/internal/1006(a)172.16.200.60] 2011-03-05 23:21:11.381511 [DEBUG] switch_ivr_bridge.c:601 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_ivr_bridge.c:581 BRIDGE THREAD DONE [loopback/voicemail-a] 2011-03-05 23:21:11.381511 [DEBUG] switch_ivr_bridge.c:601 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] switch_channel.c:2545 (loopback/voicemail-a) Callstate Change EARLY -> HANGUP 2011-03-05 23:21:11.381511 [NOTICE] switch_ivr_bridge.c:653 Hangup loopback/voicemail-a [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2011-03-05 23:21:11.381511 [DEBUG] switch_channel.c:2561 Send signal loopback/voicemail-a [KILL] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:369 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_HANGUP 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:560 (loopback/voicemail-a) State HANGUP 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:415 loopback/voicemail-a CHANNEL HANGUP 2011-03-05 23:21:11.381511 [DEBUG] switch_channel.c:2545 (loopback/voicemail-b) Callstate Change EARLY -> HANGUP 2011-03-05 23:21:11.381511 [NOTICE] mod_loopback.c:426 Hangup loopback/voicemail-b [CS_EXECUTE] [NORMAL_CLEARING] 2011-03-05 23:21:11.381511 [DEBUG] switch_channel.c:2561 Send signal loopback/voicemail-b [KILL] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-b [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-b CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:46 loopback/voicemail-a Standard HANGUP, cause: NORMAL_CLEARING 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:560 (loopback/voicemail-a) State HANGUP going to sleep 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:351 (loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING 2011-03-05 23:21:11.381511 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:320 (loopback/voicemail-a) Running State Change CS_REPORTING 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:620 (loopback/voicemail-a) State REPORTING 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:53 loopback/voicemail-a Standard REPORTING, cause: NORMAL_CLEARING 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:620 (loopback/voicemail-a) State REPORTING going to sleep 2011-03-05 23:21:11.381511 [DEBUG] switch_core_state_machine.c:345 (loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY 2011-03-05 23:21:11.381511 [DEBUG] switch_core_session.c:1116 Send signal loopback/voicemail-a [BREAK] 2011-03-05 23:21:11.381511 [DEBUG] mod_loopback.c:469 loopback/voicemail-a CHANNEL KILL 2011-03-05 23:21:11.381511 [DEBUG] switch_core_session.c:1288 Session 163 (loopback/voicemail-a) Locked, Waiting on external entities 2011-03-05 23:21:11.384116 [DEBUG] switch_ivr_bridge.c:1308 sofia/internal/1006(a)172.16.200.60 skip receive message [UNBRIDGE] (channel is hungup already) 2011-03-05 23:21:11.384116 [DEBUG] switch_core_session.c:2060 sofia/internal/1006(a)172.16.200.60 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:366 (sofia/internal/1006(a)172.16.200.60) State EXECUTE going to sleep 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/1006(a)172.16.200.60) Running State Change CS_HANGUP 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/1006(a)172.16.200.60) State HANGUP 2011-03-05 23:21:11.384116 [DEBUG] mod_sofia.c:451 sofia/internal/1006(a)172.16.200.60 Overriding SIP cause 480 with 200 from the other leg 2011-03-05 23:21:11.384116 [DEBUG] mod_sofia.c:457 Channel sofia/internal/1006(a)172.16.200.60 hanging up, cause: NORMAL_CLEARING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:46 sofia/internal/1006(a)172.16.200.60 Standard HANGUP, cause: NORMAL_CLEARING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/1006(a)172.16.200.60) State HANGUP going to sleep 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/1006(a)172.16.200.60) State Change CS_HANGUP -> CS_REPORTING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/1006(a)172.16.200.60) Running State Change CS_REPORTING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/1006(a)172.16.200.60) State REPORTING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:53 sofia/internal/1006(a)172.16.200.60 Standard REPORTING, cause: NORMAL_CLEARING 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/1006(a)172.16.200.60) State REPORTING going to sleep 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/1006(a)172.16.200.60) State Change CS_REPORTING -> CS_DESTROY 2011-03-05 23:21:11.384116 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/1006(a)172.16.200.60 [BREAK] 2011-03-05 23:21:11.384116 [DEBUG] switch_core_session.c:1288 Session 161 (sofia/internal/1006(a)172.16.200.60) Locked, Waiting on external entities 2011-03-05 23:21:11.384116 [NOTICE] switch_core_session.c:1306 Session 161 (sofia/internal/1006(a)172.16.200.60) Ended 2011-03-05 23:21:11.384116 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/1006(a)172.16.200.60 [CS_DESTROY] 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/1006(a)172.16.200.60) Callstate Change HANGUP -> DOWN 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/1006(a)172.16.200.60) Running State Change CS_DESTROY 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/1006(a)172.16.200.60) State DESTROY 2011-03-05 23:21:11.384116 [DEBUG] mod_sofia.c:362 sofia/internal/1006(a)172.16.200.60 SOFIA DESTROY 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1006(a)172.16.200.60 Standard DESTROY 2011-03-05 23:21:11.384116 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/1006(a)172.16.200.60) State DESTROY going to sleep I'm not sure how to avoid so much output, but I hope it's enough information to sort this out. Thanks, Mitch

2 1

Virtualisation and freeswitch
by Grygoriy Dobrovolskyy 08 Mar '11

08 Mar '11

Hello list, le me introduce myself first, I am Grigoriy Dobrovolskyy currently working as voip administrator and call centeres integrator. I worked a lot with asterisk, i think Fs is a great software but not easy to learn. I have googled my question and did not found a clear answer. With what technology i can virtualise freeswitch without timer problems ? I know that i can use OpenVZ but it's more 'jail' then virtualisation. I heard that Amazon EC with High CPU instance works (confirm?) Can someone give me info of a succesfull installations with Conferences, Moh / mixing ? And a technology used ? Thank you.

13 17

Standard Functionality of Freeswitch as Conference Server? Automatic Listen only conference.
by Phil T 08 Mar '11

08 Mar '11

Hi, I'm trying to setup a conference server for a local charity but I wanted to check on the standard conference functionality of Freeswitch before I started. The requirements are quite simple- Conference call only (no extra pbx features) for up to 10 people (via Public SIP trunks), but the critical part is there will be one presenter & 9 people listening only. So ideally I want to have two pin codes- one for use by the presenter, and the other pin code for listen only attendees. The listen only people should be automatically muted when they join, and obviously the presenter should have full duplex audio. There might be the requirement to allow individual listen only attendee to opt in/out to speak on the call (via a feature code i.e. *6). This type of feature is common among commercial conference solutions, is it available on Freeswitch via standard scripting without having to have a custom programmed module? If the answer is yes- I'll get started on setting it up. Any tips greatly appreciated! Finally- I have very little linux command line experience, so I'd like to do the xml script setup/ testing on a Windows 7 pc, then move over to Linux if the performance is required. Is that a sensible approach? Is there a GUI to help with the Freeswitch setup? Thanks Phil

2 1

database is locked
by elijah 08 Mar '11

08 Mar '11

I updated (via Git) today an installation of FreeSWITCH I've been running without incident for a few months. Upon restarting the FreeSWITCH process I receive at a constant rate these errors: ...switch_core_sqldb.c xxx SQL ERR [database is locked] ...switch_core_sqldb.c xxx SQL ERR [database is locked] ...switch_core_sqldb.c xxx SQL ERR [database is locked] How should I resolve?

4 6

mod_python
by hkalyoncu 08 Mar '11

08 Mar '11

hello im trying to write a dynamic dialplan module with mod_python. i did the dialplan binding of my module in python configuration file. and im using xml_fetch in my module. everything is ok up to that. But i cannot find how i can access channel variables from my module? (absolutely no docs for this) can someone help how can i dump all channel variables to a file from my module? -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/mod-python-tp6088430p6088430.… Sent from the freeswitch-users mailing list archive at Nabble.com.

3 6

Re: [Freeswitch-users] DTMF not taken into account within a conference
by John Smith 08 Mar '11

08 Mar '11

Yes, you are right. There were an error in my scripts and I used a version released the 20th of February. I tested with the latest git (git-b3a2fa1 2011-03-03 20-07-43 -0600) and everything is working fine. Thanks a lot for your help. John 2011/3/7 Brian West <brian(a)freeswitch.org> > Well I have a feeling you haven't really updated to the VERY latest GIT > HEAD have you? We had one day this past month that had this EXACT bug in it > and has since been fixed. > > /b > > On Mar 7, 2011, at 11:42 AM, John Smith wrote: > > > When I enter a DTMF within a conference it only works the first time. For > > example if I press 0 I’m muted but afterwards my DTMF commands are not > taken > > into account whatever I press. > > > _______________________________________________ > FreeSWITCH-users mailing list > FreeSWITCH-users(a)lists.freeswitch.org > http://lists.freeswitch.org/mailman/listinfo/freeswitch-users > UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users > http://www.freeswitch.org >

1 0

Skype - video and audio can it be done?
by Shamun toha md 08 Mar '11

08 Mar '11

Dear Experts, I have FS Skype running for audio. - But how can i send a SIP video/audio calls to Skype video/audio? or is that impossible for video? Thanks

2 1

static rtpmap entries are not shown in outbound invites
by Leon de Rooij 08 Mar '11

08 Mar '11

Hi, When I'm using late negotiation and in my dialplan do the following: <action application="export" data="codec_string=${ep_codec_string}"/> <action application="set" data="inherit_codec=true"/> (I also tried with absolute_codec_string instead of codec_string) Then the codecs (or more precisely said, the rtpmap lines) that are offered in the SDP of the A leg are not offered to the b-leg. Only the RTP payload numbers as defined by IANA are there in the media (m) header. For example: a-leg SDP in INVITE: m=audio 16864 RTP/AVP 18 101. a=rtpmap:18 G729/8000. a=rtpmap:101 telephone-event/8000. b-leg SDP in INVITE: m=audio 24148 RTP/AVP 18 101 13. a=rtpmap:101 telephone-event/8000. Is there a reason why FS removes the G729 rtpmap, even though I exported all into the codec_string chan var ? Now, this would normally be fine because G729 being 18 is a static rtpmap, but I have one endpoint that has the behavior that if I send an invite with RTP payload 18 and omit the rtpmap being G729, then it responds with a 183 session progress containing rtpmap G.729 (notice the dot) - this is wrong as far as I can tell. Apparently they respond with the correct G729 (without the dot) in their 183 sess progress if I include the rtpmap in my invite. I believe I read somewhere there was an option settable (on a sip profile?) that enables to always send all payloads as defined in the media (m) sdp header as a=rtpmap lines, but I cannot find the option anymore (or perhaps I'm mistaken and saw it somewhere else ?) Can someone help me with this ? Thanks & kind regards, Leon

3 4

send fax via google voice.
by envelopes envelopes 08 Mar '11

08 Mar '11

Google Voice integration with FS works fine. but sending fax seems not working. 12068235374 is a K7 number, If I dial it , I can hear fax machine answering sound. In fact, people has used GV to send fax via fax machine. ( http://www.magicjacksupport.com/faxing-via-google-voice-t7412.html) Is this problem related with mod_dingaling? please take a look! thanks itch@internal> originate dingaling/xmppc/+12068235374(a)voice.google.com&txfax(/tmp/test.tiff) +OK c20e3fc4-4784-11e0-8d1d-eda21484fd55 2011-03-05 16:00:33.808039 [NOTICE] switch_channel.c:808 New Channel dingaling/xmppc/+12068235374(a)voice.google.com[c20e3fc4-4784-11e0-8d1d-eda21484fd55] freeswitch@internal> 2011-03-05 16:00:33.809595 [DEBUG] mod_dingaling.c:1824 (dingaling/xmppc/+12068235374(a)voice.google.com) State Change CS_NEW -> CS_INIT 2011-03-05 16:00:33.809595 [DEBUG] switch_core_session.c:1116 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [BREAK] 2011-03-05 16:00:33.809595 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:33.810718 [DEBUG] switch_core_state_machine.c:320 (dingaling/xmppc/+12068235374(a)voice.google.com) Running State Change CS_INIT 2011-03-05 16:00:33.810718 [DEBUG] switch_core_state_machine.c:356 (dingaling/xmppc/+12068235374(a)voice.google.com) State INIT 2011-03-05 16:00:33.810718 [NOTICE] mod_dingaling.c:1110 Ring-Ready dingaling/xmppc/+12068235374(a)voice.google.com! 2011-03-05 16:00:33.811202 [DEBUG] mod_dingaling.c:1063 Don't have my codec yet here's one 2011-03-05 16:00:33.811202 [DEBUG] mod_dingaling.c:1083 Send Describe [PCMU@8000] 2011-03-05 16:00:34.000304 [DEBUG] mod_dingaling.c:2941 using Existing session for 6100480476 2011-03-05 16:00:34.000304 [DEBUG] mod_dingaling.c:1083 Send Describe [PCMU@8000] 2011-03-05 16:00:34.311385 [DEBUG] mod_dingaling.c:2941 using Existing session for 6100480476 2011-03-05 16:00:34.311385 [DEBUG] mod_dingaling.c:1008 Send Candidate 192.168.1.146:17960 [9CsLT8VWsB1yDqnv] 2011-03-05 16:00:34.707433 [DEBUG] mod_dingaling.c:2941 using Existing session for 6100480476 2011-03-05 16:00:34.707433 [DEBUG] mod_dingaling.c:3279 3 candidates 2011-03-05 16:00:34.707433 [DEBUG] mod_dingaling.c:3299 candidate 74.125.53.126:19295 PASS ACL wan.auto 2011-03-05 16:00:34.707433 [DEBUG] mod_dingaling.c:3351 Acceptable Candidate 74.125.53.126:19295 2011-03-05 16:00:40.334509 [DEBUG] mod_dingaling.c:2941 using Existing session for 6100480476 2011-03-05 16:00:40.334509 [DEBUG] mod_dingaling.c:3193 Already decided on a codec 2011-03-05 16:00:40.336590 [DEBUG] mod_dingaling.c:865 Set Read Codec to PCMU@8000 2011-03-05 16:00:40.336590 [DEBUG] mod_dingaling.c:880 Set Write Codec to PCMU@8000 2011-03-05 16:00:40.337483 [DEBUG] mod_dingaling.c:892 SETUP RTP 192.168.1.146:17960 -> 74.125.53.126:19295 2011-03-05 16:00:40.337483 [DEBUG] switch_rtp.c:1429 Starting timer [soft] 160 bytes per 20ms 2011-03-05 16:00:40.339652 [DEBUG] switch_rtp.c:3775 Activate VAD codec PCMU 20ms 2011-03-05 16:00:40.340311 [DEBUG] switch_channel.c:2782 (dingaling/xmppc/+ 12068235374(a)voice.google.com) Callstate Change DOWN -> ACTIVE 2011-03-05 16:00:40.340311 [NOTICE] mod_dingaling.c:1202 Channel [dingaling/xmppc/+12068235374(a)voice.google.com] has been answered 2011-03-05 16:00:40.340311 [DEBUG] mod_dingaling.c:1205 (dingaling/xmppc/+ 12068235374(a)voice.google.com) State Change CS_INIT -> CS_ROUTING 2011-03-05 16:00:40.340311 [DEBUG] switch_core_session.c:1116 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [BREAK] 2011-03-05 16:00:40.340311 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:40.340311 [DEBUG] switch_core_state_machine.c:356 (dingaling/xmppc/+12068235374(a)voice.google.com) State INIT going to sleep 2011-03-05 16:00:40.340311 [DEBUG] switch_core_state_machine.c:320 (dingaling/xmppc/+12068235374(a)voice.google.com) Running State Change CS_ROUTING 2011-03-05 16:00:40.340311 [DEBUG] switch_channel.c:1657 (dingaling/xmppc/+ 12068235374(a)voice.google.com) Callstate Change ACTIVE -> RINGING 2011-03-05 16:00:40.340311 [DEBUG] switch_core_state_machine.c:359 (dingaling/xmppc/+12068235374(a)voice.google.com) State ROUTING 2011-03-05 16:00:40.340311 [DEBUG] mod_dingaling.c:1219 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL ROUTING 2011-03-05 16:00:40.340311 [DEBUG] switch_ivr_originate.c:66 (dingaling/xmppc/+12068235374(a)voice.google.com) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-03-05 16:00:40.340311 [DEBUG] switch_core_session.c:1116 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [BREAK] 2011-03-05 16:00:40.340311 [DEBUG] switch_ivr_originate.c:3388 Originate Resulted in Success: [dingaling/xmppc/+12068235374(a)voice.google.com] 2011-03-05 16:00:40.340311 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:40.340311 [DEBUG] mod_commands.c:3194 (dingaling/xmppc/+ 12068235374(a)voice.google.com) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2011-03-05 16:00:40.340311 [DEBUG] switch_core_session.c:1116 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [BREAK] 2011-03-05 16:00:40.340311 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:40.340311 [DEBUG] switch_core_state_machine.c:359 (dingaling/xmppc/+12068235374(a)voice.google.com) State ROUTING going to sleep 2011-03-05 16:00:40.340311 [DEBUG] switch_core_state_machine.c:320 (dingaling/xmppc/+12068235374(a)voice.google.com) Running State Change CS_EXECUTE 2011-03-05 16:00:40.340311 [DEBUG] switch_channel.c:1659 (dingaling/xmppc/+ 12068235374(a)voice.google.com) Callstate Change RINGING -> ACTIVE 2011-03-05 16:00:40.342729 [DEBUG] switch_core_state_machine.c:366 (dingaling/xmppc/+12068235374(a)voice.google.com) State EXECUTE 2011-03-05 16:00:40.342729 [DEBUG] mod_dingaling.c:1236 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL EXECUTE 2011-03-05 16:00:40.342729 [DEBUG] switch_core_state_machine.c:157 dingaling/xmppc/+12068235374(a)voice.google.com Standard EXECUTE EXECUTE dingaling/xmppc/+12068235374(a)voice.google.comtxfax(/tmp/test.tiff) 2011-03-05 16:00:40.344059 [DEBUG] mod_spandsp_fax.c:1092 Raw read codec activation Success L16 20000 2011-03-05 16:00:40.344059 [DEBUG] switch_core_codec.c:116 dingaling/xmppc/+ 12068235374(a)voice.google.com Push codec L16:10 2011-03-05 16:00:40.344059 [DEBUG] mod_spandsp_fax.c:1108 Raw write codec activation Success L16 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:2941 using Existing session for 6100480476 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:3411 hungup dingaling/xmppc/+12068235374(a)voice.google.com 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:710 Terminate called from line 3412 state=CS_EXECUTE 2011-03-05 16:00:42.649258 [DEBUG] switch_channel.c:2535 (dingaling/xmppc/+ 12068235374(a)voice.google.com) Callstate Change ACTIVE -> HANGUP 2011-03-05 16:00:42.649258 [NOTICE] mod_dingaling.c:731 Hangup dingaling/xmppc/+12068235374(a)voice.google.com [CS_EXECUTE] [NORMAL_CLEARING] 2011-03-05 16:00:42.649258 [DEBUG] switch_channel.c:2551 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [KILL] 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:42.649258 [DEBUG] switch_core_session.c:1116 Send signal dingaling/xmppc/+12068235374(a)voice.google.com [BREAK] 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:1348 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL KILL 2011-03-05 16:00:42.649258 [DEBUG] mod_dingaling.c:3413 End Call 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:323 ============================================================================== 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:335 Fax processing not successful - result (49) The call dropped prematurely. 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:340 Remote station id: 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:341 Local station id: SpanDSP Fax Ident 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:342 Pages transferred: 0 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:344 Total fax pages: 0 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:345 Image resolution: 0x0 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:346 Transfer Rate: 14400 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:348 ECM status off 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:349 remote country: 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:350 remote vendor: 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:351 remote model: 2011-03-05 16:00:42.658379 [DEBUG] mod_spandsp_fax.c:353 ============================================================================== 2011-03-05 16:00:42.658379 [DEBUG] switch_core_codec.c:141 dingaling/xmppc/+ 12068235374(a)voice.google.com Restore previous codec PCMU:0. 2011-03-05 16:00:42.658379 [DEBUG] switch_core_session.c:2045 dingaling/xmppc/+12068235374(a)voice.google.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2011-03-05 16:00:42.658379 [DEBUG] switch_core_state_machine.c:366 (dingaling/xmppc/+12068235374(a)voice.google.com) State EXECUTE going to sleep 2011-03-05 16:00:42.658379 [DEBUG] switch_core_state_machine.c:320 (dingaling/xmppc/+12068235374(a)voice.google.com) Running State Change CS_HANGUP 2011-03-05 16:00:42.659331 [DEBUG] switch_core_state_machine.c:557 (dingaling/xmppc/+12068235374(a)voice.google.com) State HANGUP 2011-03-05 16:00:42.659331 [DEBUG] mod_dingaling.c:1317 dingaling/xmppc/+ 12068235374(a)voice.google.com CHANNEL HANGUP

6 19

Invitation to connect on LinkedIn
by Manish Mishra 08 Mar '11

08 Mar '11

LinkedIn ------------ I'd like to add you to my professional network on LinkedIn. - Manish Manish Mishra Mobile App Developer at Techshastra (I) Pvt Ltd Mumbai Mumbai Area, India Confirm that you know Manish Mishra https://www.linkedin.com/e/xbphn8-gl0c4cun-4t/isd/2460946473/THWHKqcI/ -- (c) 2011, LinkedIn Corporation

1 0