[Freeswitch-users] Shared Call appearence, barging and presence
Gourav Vohra
gourav at rentec.com
Wed Apr 13 06:59:15 MSD 2011
Brian,
Thanks for your help.
Following configs on phone2 and phone3.
Following is the phone2 polycom config. x2995 is on this phone.
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<!-- Generated reg-advanced.cfg Configuration File -->
<polycomConfig xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="polycomConfig.xsd">
<call call.transferOnConferenceEnd="1">
<call.autoAnswer call.autoAnswer.H323="0" call.autoAnswer.SIP="0" call.autoAnswer.micMute="1" call.autoAnswer.ringClass="ringAutoAnswer" call.autoAnswer.videoMute="0">
</call.autoAnswer>
<call.autoOffHook call.autoOffHook.1.contact="" call.autoOffHook.1.enabled="0" call.autoOffHook.1.protocol="" call.autoOffHook.2.contact="" call.autoOffHook.2.enabled="0" call.autoOffHook.2.protocol="">
</call.autoOffHook>
<call.donotdisturb call.donotdisturb.perReg="0">
</call.donotdisturb>
<call.missedCallTracking call.missedCallTracking.1.enabled="1" call.missedCallTracking.2.enabled="1">
</call.missedCallTracking>
<call.serverMissedCall call.serverMissedCall.1.enabled="0" call.serverMissedCall.2.enabled="0">
</call.serverMissedCall>
</call>
<device>
<device.auth device.auth.localUserPassword="">
<device.auth.localUserPassword device.auth.localUserPassword.set="0">
</device.auth.localUserPassword>
</device.auth>
<device.em device.em.power="">
<device.em.power device.em.power.set="0">
</device.em.power>
</device.em>
</device>
<reg reg.1.displayName="test user1"
reg.1.address="2995"
reg.1.label="2995"
reg.1.type="shared"
reg.1.auth.userId="2995"
reg.1.auth.password="zebra"
reg.1.ringType="2"
reg.1.bargeInEnabled="1"
reg.1.callsPerLineKey="4"
reg.1.csta="0"
reg.1.fwd.busy.contact=""
reg.1.fwd.busy.status="0"
reg.1.fwd.noanswer.contact=""
reg.1.fwd.noanswer.ringCount="0"
reg.1.fwd.noanswer.status="0"
reg.1.lineKeys="1"
reg.1.musicOnHold.uri=""
reg.1.outboundProxy.failOver.failBack.timeout="3600"
reg.1.outboundProxy.failOver.failRegistrationOn="1"
reg.1.outboundProxy.failOver.reRegisterOn="0"
reg.1.outboundProxy.address="192.168.100.33"
reg.1.outboundProxy.port="5060"
reg.1.protocol.SIP="1"
reg.1.ringType="ringer2"
reg.1.serverFeatureControl.cf="1"
reg.1.serverFeatureControl.dnd="1"
reg.1.thirdPartyName=""
reg.1.type="private"
reg.1.useCompleteUriForRetrieve="1"
reg.1.server.1.expires="3600"
reg.1.server.1.expires.lineSeize="30"
reg.1.server.1.expires.overlap="60"
reg.1.server.1.lcs="0"
reg.1.server.1.retryMaxCount="3"
reg.1.server.1.retryTimeOut="0"
reg.1.server.2.expires="3600"
reg.1.server.2.expires.lineSeize="30"
reg.1.server.2.expires.overlap="60"
reg.1.server.2.lcs="0"
reg.1.server.2.retryMaxCount="3"
reg.1.server.2.retryTimeOut="0">
</reg>
<se se.destination="chassis">
<se.rt>
<se.rt.modification se.rt.modification.enabled="1">
</se.rt.modification>
</se.rt>
</se>
<up up.25mm="0" up.accessibilityFeatures="0" up.analogHeadsetOption="0" up.audioMode="0" up.callTypeOrderVoice="0" up.handsfreeMode="1" up.headsetMode="0" up.localClockEnabled="1" up.manualProtocolRouting="1" up.mwiVisible="0" up.onHookDialingEnabled="1" up.useDirectoryNames="0" up.welcomeSoundEnabled="1" up.welcomeSoundOnWarmBootEnabled="0">
<up.2P5mmJack up.2P5mmJack.mode="0">
</up.2P5mmJack>
<up.audioSetup up.audioSetup.auxInput="2" up.audioSetup.auxOutput="2">
</up.audioSetup>
<up.backlight up.backlight.idleIntensity="1" up.backlight.onIntensity="3">
</up.backlight>
<up.idleBrowser up.idleBrowser.enabled="0">
</up.idleBrowser>
<up.manualProtocolRouting up.manualProtocolRouting.softKeys="1">
</up.manualProtocolRouting>
<up.offHookAction up.offHookAction.none="0">
</up.offHookAction>
<up.pictureFrame up.pictureFrame.folder="" up.pictureFrame.timePerImage="5">
</up.pictureFrame>
<up.screenSaver up.screenSaver.enabled="0" up.screenSaver.waitTime="15">
</up.screenSaver>
<up.toneControl up.toneControl.bass="0" up.toneControl.treble="0">
</up.toneControl>
</up>
<voIpProt>
<voIpProt.SIP>
<voIpProt.SIP.assuredService>
<voIpProt.SIP.assuredService.namespace>
<voIpProt.SIP.assuredService.namespace.label>
<voIpProt.SIP.assuredService.namespace.label.UCRdrsn voIpProt.SIP.assuredService.namespace.label.UCRdrsn.1="" voIpProt.SIP.assuredService.namespace.label.UCRdrsn.2="">
</voIpProt.SIP.assuredService.namespace.label.UCRdrsn>
<voIpProt.SIP.assuredService.namespace.label.UCRdsn voIpProt.SIP.assuredService.namespace.label.UCRdsn.1="" voIpProt.SIP.assuredService.namespace.label.UCRdsn.2="">
</voIpProt.SIP.assuredService.namespace.label.UCRdsn>
<voIpProt.SIP.assuredService.namespace.label.custom voIpProt.SIP.assuredService.namespace.label.custom.1="" voIpProt.SIP.assuredService.namespace.label.custom.2="">
</voIpProt.SIP.assuredService.namespace.label.custom>
<voIpProt.SIP.assuredService.namespace.label.drsn voIpProt.SIP.assuredService.namespace.label.drsn.1="" voIpProt.SIP.assuredService.namespace.label.drsn.2="">
</voIpProt.SIP.assuredService.namespace.label.drsn>
<voIpProt.SIP.assuredService.namespace.label.dsn voIpProt.SIP.assuredService.namespace.label.dsn.1="" voIpProt.SIP.assuredService.namespace.label.dsn.2="">
</voIpProt.SIP.assuredService.namespace.label.dsn>
</voIpProt.SIP.assuredService.namespace.label>
</voIpProt.SIP.assuredService.namespace>
</voIpProt.SIP.assuredService>
</voIpProt.SIP>
</voIpProt>
</polycomConfig>
Following is the phone3 polycom config. x2996 and x2995 are on this phone.
<?xml version="1.0" encoding="UTF-8" standalone="yes"?>
<!-- Generated reg-advanced.cfg Configuration File -->
<polycomConfig xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:noNamespaceSchemaLocation="polycomConfig.xsd">
<call call.transferOnConferenceEnd="1">
<call.autoAnswer call.autoAnswer.H323="0" call.autoAnswer.SIP="0" call.autoAnswer.micMute="1" call.autoAnswer.ringClass="ringAutoAnswer" call.autoAnswer.videoMute="0">
</call.autoAnswer>
<call.autoOffHook call.autoOffHook.1.contact="" call.autoOffHook.1.enabled="0" call.autoOffHook.1.protocol="" call.autoOffHook.2.contact="" call.autoOffHook.2.enabled="0" call.autoOffHook.2.protocol="">
</call.autoOffHook>
<call.donotdisturb call.donotdisturb.perReg="0">
</call.donotdisturb>
<call.missedCallTracking call.missedCallTracking.1.enabled="1" call.missedCallTracking.2.enabled="1">
</call.missedCallTracking>
<call.serverMissedCall call.serverMissedCall.1.enabled="0" call.serverMissedCall.2.enabled="0">
</call.serverMissedCall>
</call>
<device>
<device.auth device.auth.localUserPassword="">
<device.auth.localUserPassword device.auth.localUserPassword.set="0">
</device.auth.localUserPassword>
</device.auth>
<device.em device.em.power="">
<device.em.power device.em.power.set="0">
</device.em.power>
</device.em>
</device>
<reg reg.1.displayName="test user2"
reg.1.address="2996"
reg.1.label="2996"
reg.1.type="private"
reg.1.auth.userId="2996"
reg.1.auth.password="zebra"
reg.1.ringType="2"
reg.1.bargeInEnabled="1"
reg.1.callsPerLineKey="4"
reg.1.csta="0"
reg.1.fwd.busy.contact=""
reg.1.fwd.busy.status="0"
reg.1.fwd.noanswer.contact=""
reg.1.fwd.noanswer.ringCount="0"
reg.1.fwd.noanswer.status="0"
reg.1.lineKeys="1"
reg.1.musicOnHold.uri=""
reg.1.outboundProxy.failOver.failBack.timeout="3600"
reg.1.outboundProxy.failOver.failRegistrationOn="1"
reg.1.outboundProxy.failOver.reRegisterOn="0"
reg.1.outboundProxy.address="192.168.100.33"
reg.1.outboundProxy.port="5060"
reg.1.protocol.SIP="1"
reg.1.ringType="ringer2"
reg.1.serverFeatureControl.cf="1"
reg.1.serverFeatureControl.dnd="1"
reg.1.thirdPartyName=""
reg.1.type="private"
reg.1.useCompleteUriForRetrieve="1"
reg.1.server.1.expires="3600"
reg.1.server.1.expires.lineSeize="30"
reg.1.server.1.expires.overlap="60"
reg.1.server.1.lcs="0"
reg.1.server.1.retryMaxCount="3"
reg.1.server.1.retryTimeOut="0"
reg.1.server.2.expires="3600"
reg.1.server.2.expires.lineSeize="30"
reg.1.server.2.expires.overlap="60"
reg.1.server.2.lcs="0"
reg.1.server.2.retryMaxCount="3"
reg.1.server.2.retryTimeOut="0"
reg.2.displayName="test user1"
reg.2.address="2995"
reg.2.label="2995"
reg.2.type="shared"
reg.2.auth.userId="2995"
reg.2.auth.password="zebra"
reg.2.ringType="2"
reg.2.bargeInEnabled="1"
reg.2.callsPerLineKey="4"
reg.2.csta="0"
reg.2.fwd.busy.contact=""
reg.2.fwd.busy.status="0"
reg.2.fwd.noanswer.contact=""
reg.2.fwd.noanswer.ringCount="0"
reg.2.fwd.noanswer.status="0"
reg.2.lineKeys="1"
reg.2.musicOnHold.uri=""
reg.2.outboundProxy.failOver.failBack.timeout="3600"
reg.2.outboundProxy.failOver.failRegistrationOn="1"
reg.2.outboundProxy.failOver.reRegisterOn="0"
reg.2.outboundProxy.address="192.168.100.33"
reg.2.outboundProxy.port="5060"
reg.2.protocol.SIP="1"
reg.2.ringType="ringer2"
reg.2.serverFeatureControl.cf="1"
reg.2.serverFeatureControl.dnd="1"
reg.2.thirdPartyName=""
reg.2.type="private"
reg.2.useCompleteUriForRetrieve="1"
reg.2.server.1.expires="3600"
reg.2.server.1.expires.lineSeize="30"
reg.2.server.1.expires.overlap="60"
reg.2.server.1.lcs="0"
reg.2.server.1.retryMaxCount="3"
reg.2.server.1.retryTimeOut="0"
reg.2.server.2.expires="3600"
reg.2.server.2.expires.lineSeize="30"
reg.2.server.2.expires.overlap="60"
reg.2.server.2.lcs="0"
reg.2.server.2.retryMaxCount="3"
reg.2.server.2.retryTimeOut="0">
</reg>
<se se.destination="chassis">
<se.rt>
<se.rt.modification se.rt.modification.enabled="1">
</se.rt.modification>
</se.rt>
</se>
<up up.25mm="0" up.accessibilityFeatures="0" up.analogHeadsetOption="0" up.audioMode="0" up.callTypeOrderVoice="0" up.handsfreeMode="1" up.headsetMode="0" up.localClockEnabled="1" up.manualProtocolRouting="1" up.mwiVisible="0" up.onHookDialingEnabled="1" up.useDirectoryNames="0" up.welcomeSoundEnabled="1" up.welcomeSoundOnWarmBootEnabled="0">
<up.2P5mmJack up.2P5mmJack.mode="0">
</up.2P5mmJack>
<up.audioSetup up.audioSetup.auxInput="2" up.audioSetup.auxOutput="2">
</up.audioSetup>
<up.backlight up.backlight.idleIntensity="1" up.backlight.onIntensity="3">
</up.backlight>
<up.idleBrowser up.idleBrowser.enabled="0">
</up.idleBrowser>
<up.manualProtocolRouting up.manualProtocolRouting.softKeys="1">
</up.manualProtocolRouting>
<up.offHookAction up.offHookAction.none="0">
</up.offHookAction>
<up.pictureFrame up.pictureFrame.folder="" up.pictureFrame.timePerImage="5">
</up.pictureFrame>
<up.screenSaver up.screenSaver.enabled="0" up.screenSaver.waitTime="15">
</up.screenSaver>
<up.toneControl up.toneControl.bass="0" up.toneControl.treble="0">
</up.toneControl>
</up>
<voIpProt>
<voIpProt.SIP>
<voIpProt.SIP.assuredService>
<voIpProt.SIP.assuredService.namespace>
<voIpProt.SIP.assuredService.namespace.label>
<voIpProt.SIP.assuredService.namespace.label.UCRdrsn voIpProt.SIP.assuredService.namespace.label.UCRdrsn.1="" voIpProt.SIP.assuredService.namespace.label.UCRdrsn.2="">
</voIpProt.SIP.assuredService.namespace.label.UCRdrsn>
<voIpProt.SIP.assuredService.namespace.label.UCRdsn voIpProt.SIP.assuredService.namespace.label.UCRdsn.1="" voIpProt.SIP.assuredService.namespace.label.UCRdsn.2="">
</voIpProt.SIP.assuredService.namespace.label.UCRdsn>
<voIpProt.SIP.assuredService.namespace.label.custom voIpProt.SIP.assuredService.namespace.label.custom.1="" voIpProt.SIP.assuredService.namespace.label.custom.2="">
</voIpProt.SIP.assuredService.namespace.label.custom>
<voIpProt.SIP.assuredService.namespace.label.drsn voIpProt.SIP.assuredService.namespace.label.drsn.1="" voIpProt.SIP.assuredService.namespace.label.drsn.2="">
</voIpProt.SIP.assuredService.namespace.label.drsn>
<voIpProt.SIP.assuredService.namespace.label.dsn voIpProt.SIP.assuredService.namespace.label.dsn.1="" voIpProt.SIP.assuredService.namespace.label.dsn.2="">
</voIpProt.SIP.assuredService.namespace.label.dsn>
</voIpProt.SIP.assuredService.namespace.label>
</voIpProt.SIP.assuredService.namespace>
</voIpProt.SIP.assuredService>
</voIpProt.SIP>
</voIpProt>
</polycomConfig>
Thanks.-
----- Original Message -----
From: "Brian West" <brian at freeswitch.org>
To: "FreeSWITCH Users Help" <freeswitch-users at lists.freeswitch.org>
Sent: Tuesday, April 12, 2011 8:53:30 PM
Subject: Re: [Freeswitch-users] Shared Call appearence, barging and presence
I would need to see your polycom config options I have tested with 3.3.1 and it works fine... so please provide me some info on how you setup the phones... also please don't hijack threads. Please click new message and input the list address. You have clicked reply on the CALL_REJECT thread and changed the subject.
Thanks,
Brian
On Apr 12, 2011, at 6:55 PM, Gourav Vohra wrote:
> It would be very helpful to know if this is how freeswitch works with SLA or if my configuration is broken.
>
>
> Thanks.-
>
> ----- Original Message -----
> From: "Gourav Vohra" <gourav at rentec.com>
> To: freeswitch-users at lists.freeswitch.org
> Sent: Tuesday, April 12, 2011 12:48:55 PM
> Subject: Shared Call appearence, barging and presence
>
>
>
> Thanks in advance with helping me with this.
>
> I am having some problems with sla.
>
> My setup includes polycom IP 650 phones (SIP version 3.3.1) and freeswitch downloaded on Apr 3 from the following link.
> http://files.freeswitch.org/freeswitch-snapshot.tar.gz
>
> Following is what my setup looks like:
> phone1 - x2908
> phone2 - x2995
> phone3 - x2996, x2995
>
> In my test I make a call from phone1 to x2995 and pick it up on phone2. At this point I see the x2995's line in use on phone3. Next I barge into the call from phone3. At this point phone1, phone2 and phone3 are all on the call that was initiated from phone1.
>
> Next I end the call on phone2.
>
> The issue I am having is that after I barge in from phone3 and "End Call" on phone2 - The call remains established between phone 3 and phone1 but x2995 on phone2 does not show that the line is in use. I believe that the call should remain established between phone1 and phone3 after phone2 drops out and the line appearance (x2995) on phone2 should look like it's still in use. The led on the polycom 650 should change to red. In my case it doesn't.
>
> On the polycom config x2995 is setup as a shared line with reg.x.bargeInEnabled set to "1".
>
> Following is set on vars.xml.
> <X-PRE-PROCESS cmd="set" data="domain=$${local_ip_v4}"/>
> <X-PRE-PROCESS cmd="set" data="domain_name=$${domain}"/>
>
> Following is set on the sip profile.
> <!--enable to use presence -->
> <param name="manage-presence" value="true"/>
> <!-- send a presence probe on each register to query devices to send presence instead of sending presence with less info -->
> <param name="presence-probe-on-register" value="true"/>-->
> <param name="manage-shared-appearance" value="true"/>
> <!-- used to share presence info across sofia profiles -->
> <!-- Name of the db to use for this profile -->
> <param name="dbname" value="share_presence"/>
> <param name="presence-hosts" value="$${domain},$${local_ip_v4}"/>
>
> Following is set on the user registration.
> <param name="manage-presence" value="passive"/>
> <param name="manage-shared-appearance" value="true"/
>
> Following is set on the dialplan for x2995 and x2996.
> <action application="bridge" data="{sip_invite_domain=$${domain_name}}user/2995@${domain}"/>
>
>
> Following logs are for the call getting barged in from phone3 and getting dropped from phone2.
>
> 2011-04-08 10:08:37.557009 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/2908.csv
> 2011-04-08 10:08:37.557009 [NOTICE] mod_logfile.c:158 New log started.
> 2011-04-08 10:09:04.414661 [DEBUG] sofia.c:6539 IP 192.168.100.75 Rejected by acl "domains". Falling back to Digest auth.
> 2011-04-08 10:09:04.414661 [WARNING] sofia_reg.c:1246 SIP auth challenge (INVITE) on sofia profile 'internal' for [2995 at 192.168.100.33] from ip 192.168.100.75
> 2011-04-08 10:09:04.428752 [DEBUG] sofia.c:6539 IP 192.168.100.75 Rejected by acl "domains". Falling back to Digest auth.
> 2011-04-08 10:09:04.428752 [NOTICE] switch_channel.c:812 New Channel sofia/internal/2995 at 192.168.100.33 [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> 2011-04-08 10:09:04.429760 [DEBUG] switch_ivr.c:1600 (sofia/internal/sip:2995 at 192.168.100.74) State Change CS_EXCHANGE_MEDIA -> CS_ROUTING
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.429760 [NOTICE] switch_ivr.c:1606 Transfer sofia/internal/sip:2995 at 192.168.100.74 to inline[answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}@default]
> 2011-04-08 10:09:04.429760 [DEBUG] switch_ivr.c:1600 (sofia/internal/2908 at 192.168.100.33) State Change CS_EXECUTE -> CS_ROUTING
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.429760 [NOTICE] switch_ivr.c:1606 Transfer sofia/internal/2908 at 192.168.100.33 to inline[answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}@default]
> 2011-04-08 10:09:04.429760 [DEBUG] sofia.c:4760 Channel sofia/internal/2995 at 192.168.100.33 entering state [received][100]
> 2011-04-08 10:09:04.429760 [DEBUG] sofia.c:4771 Remote SDP:
> v=0
> o=- 1302271492 1302271492 IN IP4 192.168.100.75
> s=Polycom IP Phone
> c=IN IP4 192.168.100.75
> t=0 0
> a=sendrecv
> m=audio 2234 RTP/AVP 9 0 8 18 127
> a=rtpmap:9 G722/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:18 G729/8000
> a=fmtp:18 annexb=no
> a=rtpmap:127 telephone-event/8000
>
> 2011-04-08 10:09:04.429760 [DEBUG] sofia_glue.c:4637 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:115:32000:20:48000]
> 2011-04-08 10:09:04.429760 [DEBUG] sofia_glue.c:4637 Audio Codec Compare [G722:9:8000:20:64000]/[G7221:107:16000:20:32000]
> 2011-04-08 10:09:04.429760 [DEBUG] sofia_glue.c:4637 Audio Codec Compare [G722:9:8000:20:64000]/[G722:9:8000:20:64000]
> 2011-04-08 10:09:04.429760 [DEBUG] sofia_glue.c:2760 Set Codec sofia/internal/2995 at 192.168.100.33 G722/8000 20 ms 160 samples 64000 bits
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_NEW
> 2011-04-08 10:09:04.429760 [DEBUG] switch_core_state_machine.c:343 (sofia/internal/2995 at 192.168.100.33) State NEW
> 2011-04-08 10:09:04.430787 [DEBUG] sofia_glue.c:4751 Set 2833 dtmf send/recv payload to 127
> 2011-04-08 10:09:04.430787 [DEBUG] sofia.c:4942 (sofia/internal/2995 at 192.168.100.33) State Change CS_NEW -> CS_INIT
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_INIT
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_state_machine.c:361 (sofia/internal/2995 at 192.168.100.33) State INIT
> 2011-04-08 10:09:04.430787 [DEBUG] mod_sofia.c:84 sofia/internal/2995 at 192.168.100.33 SOFIA INIT
> 2011-04-08 10:09:04.430787 [DEBUG] mod_sofia.c:124 (sofia/internal/2995 at 192.168.100.33) State Change CS_INIT -> CS_ROUTING
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_state_machine.c:361 (sofia/internal/2995 at 192.168.100.33) State INIT going to sleep
> 2011-04-08 10:09:04.430787 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_channel.c:1668 (sofia/internal/2995 at 192.168.100.33) Callstate Change DOWN -> RINGING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_ivr_bridge.c:582 BRIDGE THREAD DONE [sofia/internal/2908 at 192.168.100.33]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_ivr_bridge.c:602 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/2995 at 192.168.100.33) State ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:147 sofia/internal/2995 at 192.168.100.33 SOFIA ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:77 sofia/internal/2995 at 192.168.100.33 Standard ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/2995 at 192.168.100.33) State Change CS_ROUTING -> CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/2995 at 192.168.100.33) State ROUTING going to sleep
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/2995 at 192.168.100.33) State EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:240 sofia/internal/2995 at 192.168.100.33 SOFIA EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:157 sofia/internal/2995 at 192.168.100.33 Standard EXECUTE
> EXECUTE sofia/internal/2995 at 192.168.100.33 answer()
> 2011-04-08 10:09:04.431879 [DEBUG] switch_ivr_bridge.c:582 BRIDGE THREAD DONE [sofia/internal/sip:2995 at 192.168.100.74]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_ivr_bridge.c:602 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:374 (sofia/internal/sip:2995 at 192.168.100.74) State EXCHANGE_MEDIA going to sleep
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:2995 at 192.168.100.74) Running State Change CS_ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_channel.c:1668 (sofia/internal/sip:2995 at 192.168.100.74) Callstate Change ACTIVE -> RINGING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/2908 at 192.168.100.33) State EXECUTE going to sleep
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2908 at 192.168.100.33) Running State Change CS_ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_channel.c:1668 (sofia/internal/2908 at 192.168.100.33) Callstate Change ACTIVE -> RINGING
> 2011-04-08 10:09:04.431879 [DEBUG] sofia_glue.c:3001 AUDIO RTP [sofia/internal/2995 at 192.168.100.33] 192.168.100.33 port 29998 -> 192.168.100.75 port 2234 codec: 9 ms: 20
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/2908 at 192.168.100.33) State ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:147 sofia/internal/2908 at 192.168.100.33 SOFIA ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:77 sofia/internal/2908 at 192.168.100.33 Standard ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/2908 at 192.168.100.33) State Change CS_ROUTING -> CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/2908 at 192.168.100.33) State ROUTING going to sleep
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2908 at 192.168.100.33) Running State Change CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_channel.c:1670 (sofia/internal/2908 at 192.168.100.33) Callstate Change RINGING -> ACTIVE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_rtp.c:1623 Starting timer [soft] 160 bytes per 20ms
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/2908 at 192.168.100.33) State EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:240 sofia/internal/2908 at 192.168.100.33 SOFIA EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:157 sofia/internal/2908 at 192.168.100.33 Standard EXECUTE
> EXECUTE sofia/internal/2908 at 192.168.100.33 answer()
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/sip:2995 at 192.168.100.74) State ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:147 sofia/internal/sip:2995 at 192.168.100.74 SOFIA ROUTING
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:77 sofia/internal/sip:2995 at 192.168.100.74 Standard ROUTING
> 2011-04-08 10:09:04.431879 [INFO] switch_channel.c:2457 sofia/internal/sip:2995 at 192.168.100.74 Flipping CID from "Gourav Vohra" <2908> to "Outbound Call" <2995>
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/sip:2995 at 192.168.100.74) State Change CS_ROUTING -> CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:364 (sofia/internal/sip:2995 at 192.168.100.74) State ROUTING going to sleep
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/sip:2995 at 192.168.100.74) Running State Change CS_EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_channel.c:1670 (sofia/internal/sip:2995 at 192.168.100.74) Callstate Change RINGING -> ACTIVE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/sip:2995 at 192.168.100.74) State EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] mod_sofia.c:240 sofia/internal/sip:2995 at 192.168.100.74 SOFIA EXECUTE
> 2011-04-08 10:09:04.431879 [DEBUG] switch_core_state_machine.c:157 sofia/internal/sip:2995 at 192.168.100.74 Standard EXECUTE
> EXECUTE sofia/internal/sip:2995 at 192.168.100.74 answer()
> EXECUTE sofia/internal/2908 at 192.168.100.33 conference(6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo})
> EXECUTE sofia/internal/sip:2995 at 192.168.100.74 conference(6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo})
> 2011-04-08 10:09:04.433706 [INFO] mod_conference.c:6496 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
> 2011-04-08 10:09:04.433706 [DEBUG] mod_conference.c:5464 Raw Codec Activation Success L16 at 8000hz 1 channel 20ms
> 2011-04-08 10:09:04.433706 [DEBUG] mod_conference.c:5509 Raw Codec Activation Success L16 at 16000hz 1 channel 20ms
> 2011-04-08 10:09:04.433706 [DEBUG] switch_core_codec.c:116 sofia/internal/sip:2995 at 192.168.100.74 Push codec L16:70
> 2011-04-08 10:09:04.433706 [DEBUG] mod_conference.c:1069 Setup timer success interval: 20 samples: 320
> 2011-04-08 10:09:04.433706 [DEBUG] sofia_glue.c:3263 Set 2833 dtmf send payload to 127
> 2011-04-08 10:09:04.433706 [DEBUG] sofia_glue.c:3268 Set 2833 dtmf receive payload to 127
> 2011-04-08 10:09:04.433706 [DEBUG] mod_sofia.c:681 Local SDP sofia/internal/2995 at 192.168.100.33:
> v=0
> o=FreeSWITCH 1302241746 1302241747 IN IP4 192.168.100.33
> s=FreeSWITCH
> c=IN IP4 192.168.100.33
> t=0 0
> m=audio 29998 RTP/AVP 9 127
> a=rtpmap:9 G722/8000
> a=rtpmap:127 telephone-event/8000
> a=fmtp:127 0-16
> a=silenceSupp:off - - - -
> a=ptime:20
> a=sendrecv
>
> 2011-04-08 10:09:04.433706 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.433706 [DEBUG] switch_channel.c:2821 (sofia/internal/2995 at 192.168.100.33) Callstate Change RINGING -> ACTIVE
> 2011-04-08 10:09:04.433706 [NOTICE] mod_dptools.c:930 Channel [sofia/internal/2995 at 192.168.100.33] has been answered
> 2011-04-08 10:09:04.433706 [DEBUG] mod_conference.c:5464 Raw Codec Activation Success L16 at 8000hz 1 channel 20ms
> 2011-04-08 10:09:04.433706 [DEBUG] mod_conference.c:5509 Raw Codec Activation Success L16 at 16000hz 1 channel 20ms
> 2011-04-08 10:09:04.433706 [DEBUG] switch_core_codec.c:116 sofia/internal/2908 at 192.168.100.33 Push codec L16:70
> 2011-04-08 10:09:04.435298 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/sip:2995 at 192.168.100.74 [BREAK]
> 2011-04-08 10:09:04.435298 [DEBUG] mod_conference.c:2552 Setup timer soft success interval: 20 samples: 160
> 2011-04-08 10:09:04.435298 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/2908 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.435298 [DEBUG] mod_conference.c:2552 Setup timer soft success interval: 20 samples: 160
> 2011-04-08 10:09:04.435298 [DEBUG] sofia.c:4760 Channel sofia/internal/2995 at 192.168.100.33 entering state [completed][200]
> EXECUTE sofia/internal/2995 at 192.168.100.33 conference(6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo})
> 2011-04-08 10:09:04.435298 [DEBUG] mod_conference.c:5464 Raw Codec Activation Success L16 at 16000hz 1 channel 20ms
> 2011-04-08 10:09:04.435298 [DEBUG] mod_conference.c:5509 Raw Codec Activation Success L16 at 16000hz 1 channel 20ms
> 2011-04-08 10:09:04.436366 [DEBUG] switch_core_codec.c:116 sofia/internal/2995 at 192.168.100.33 Push codec L16:70
> 2011-04-08 10:09:04.436366 [DEBUG] switch_core_session.c:709 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:04.436366 [DEBUG] mod_conference.c:2552 Setup timer soft success interval: 20 samples: 160
> 2011-04-08 10:09:04.441402 [DEBUG] sofia.c:4760 Channel sofia/internal/2995 at 192.168.100.33 entering state [ready][200]
> 2011-04-08 10:09:04.511924 [DEBUG] switch_rtp.c:3082 Correct ip/port confirmed.
> 2011-04-08 10:09:04.526038 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.527046 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.527046 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744430787]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [DOWN]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [ringing]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [0]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [alerting]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [0]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.527046 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.528054 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.529062 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '2995 at 192.168.100.33' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744430787]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [DOWN]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [ringing]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [0]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [alerting]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [0]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.529062 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.529062 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.529062 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.529062 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.529062 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744431879]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/sip:2995 at 192.168.100.74]
> Unique-ID: [e508f89d-e49b-49a7-ba5b-03c822ebe75f]
> Call-Direction: [outbound]
> Presence-Call-Direction: [outbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Channel-Call-UUID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [PCMU]
> Channel-Read-Codec-Rate: [8000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [PCMU]
> Channel-Write-Codec-Rate: [8000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [outbound]
> Caller-Username: [2908]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2908]
> Caller-Callee-ID-Name: [Outbound Call]
> Caller-Callee-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.74]
> Caller-ANI: [2908]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [e508f89d-e49b-49a7-ba5b-03c822ebe75f]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-RDNIS: [2995]
> Caller-Channel-Name: [sofia/internal/sip:2995 at 192.168.100.74]
> Caller-Profile-Index: [2]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271711758979]
> Caller-Channel-Answered-Time: [1302271714953388]
> Caller-Channel-Progress-Time: [1302271711821261]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-direction: [outbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.530069 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.530069 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.530069 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '2995 at 192.168.100.33' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744431879]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/sip:2995 at 192.168.100.74]
> Unique-ID: [e508f89d-e49b-49a7-ba5b-03c822ebe75f]
> Call-Direction: [outbound]
> Presence-Call-Direction: [outbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Channel-Call-UUID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [PCMU]
> Channel-Read-Codec-Rate: [8000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [PCMU]
> Channel-Write-Codec-Rate: [8000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [outbound]
> Caller-Username: [2908]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2908]
> Caller-Callee-ID-Name: [Outbound Call]
> Caller-Callee-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.74]
> Caller-ANI: [2908]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [e508f89d-e49b-49a7-ba5b-03c822ebe75f]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-RDNIS: [2995]
> Caller-Channel-Name: [sofia/internal/sip:2995 at 192.168.100.74]
> Caller-Profile-Index: [2]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271711758979]
> Caller-Channel-Answered-Time: [1302271714953388]
> Caller-Channel-Progress-Time: [1302271711821261]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-direction: [outbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.530069 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.530069 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.530069 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.531077 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.531077 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2908' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744431879]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/2908 at 192.168.100.33]
> Unique-ID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2908 at 192.168.100.33]
> Channel-Call-UUID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [PCMU]
> Channel-Read-Codec-Rate: [8000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [PCMU]
> Channel-Write-Codec-Rate: [8000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2908]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2908]
> Caller-Callee-ID-Name: [Outbound Call]
> Caller-Callee-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.64]
> Caller-ANI: [2908]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-RDNIS: [2995]
> Caller-Channel-Name: [sofia/internal/2908 at 192.168.100.33]
> Caller-Profile-Index: [2]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271711753265]
> Caller-Channel-Answered-Time: [1302271714972507]
> Caller-Channel-Progress-Time: [1302271711821261]
> Caller-Channel-Progress-Media-Time: [1302271711822270]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2908 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [active]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.532084 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.533099 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.533099 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'CS_ROUTING','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '2908 at 192.168.100.33' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2908' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744431879]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_ROUTING]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [2]
> Channel-Name: [sofia/internal/2908 at 192.168.100.33]
> Unique-ID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2908 at 192.168.100.33]
> Channel-Call-UUID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [PCMU]
> Channel-Read-Codec-Rate: [8000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [PCMU]
> Channel-Write-Codec-Rate: [8000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2908]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2908]
> Caller-Callee-ID-Name: [Outbound Call]
> Caller-Callee-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.64]
> Caller-ANI: [2908]
> Caller-Destination-Number: [answer,conference:6c33a351-1842-4e45-9bfb-89249c44a8c6 at sla+flags{mintwo}]
> Caller-Unique-ID: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-RDNIS: [2995]
> Caller-Channel-Name: [sofia/internal/2908 at 192.168.100.33]
> Caller-Profile-Index: [2]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271711753265]
> Caller-Channel-Answered-Time: [1302271714972507]
> Caller-Channel-Progress-Time: [1302271711821261]
> Caller-Channel-Progress-Media-Time: [1302271711822270]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2908 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_ROUTING]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [active]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:04.533099 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.533099 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.533099 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.533099 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.533099 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (1 caller)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744433706]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (1 caller)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [119]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [early]
> presence-call-direction: [outbound]
>
>
> 2011-04-08 10:09:04.534119 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.534119 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.534119 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (1 caller)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744433706]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (1 caller)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [119]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [early]
> presence-call-direction: [outbound]
>
>
> 2011-04-08 10:09:04.535125 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.535125 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.535125 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.535125 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.535125 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (2 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744435298]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (2 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [120]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> presence-call-direction: [inbound]
>
>
> 2011-04-08 10:09:04.535125 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.535125 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.535125 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (2 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744435298]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (2 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [120]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> presence-call-direction: [inbound]
>
>
> 2011-04-08 10:09:04.536132 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.536132 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.536132 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.536132 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.537142 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'answered','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744435298]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_EXECUTE]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [4]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [1302271744433706]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [answered]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [active]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [1]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_mark_answered]
> Presence-Calling-Line: [2887]
>
>
> 2011-04-08 10:09:04.537142 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.538149 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.538149 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'answered','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '2995 at 192.168.100.33' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744435298]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_EXECUTE]
> Channel-Call-State: [ACTIVE]
> Channel-State-Number: [4]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [answered]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [1302271744433706]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [answered]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [active]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [1]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_mark_answered]
> Presence-Calling-Line: [2887]
>
>
> 2011-04-08 10:09:04.539155 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.539155 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:04.539155 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:04.539155 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.539155 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (3 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744436366]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (3 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [121]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> presence-call-direction: [inbound]
>
>
> 2011-04-08 10:09:04.539155 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:04.539155 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.539155 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (3 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:04]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:04 GMT]
> Event-Date-Timestamp: [1302271744436366]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_add_member]
> Event-Calling-Line-Number: [689]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (3 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [121]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> presence-call-direction: [inbound]
>
>
> 2011-04-08 10:09:04.540162 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:04.540162 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:13.943151 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/2995.csv
> 2011-04-08 10:09:13.943151 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv
>
> 2011-04-08 10:09:13.944159 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/2908.csv
> 2011-04-08 10:09:13.944159 [NOTICE] mod_logfile.c:158 New log started.
> 2011-04-08 10:09:27.679863 [DEBUG] switch_channel.c:2563 (sofia/internal/2995 at 192.168.100.33) Callstate Change ACTIVE -> HANGUP
> 2011-04-08 10:09:27.679863 [NOTICE] sofia.c:537 Hangup sofia/internal/2995 at 192.168.100.33 [CS_EXECUTE] [NORMAL_CLEARING]
> 2011-04-08 10:09:27.679863 [DEBUG] switch_channel.c:2579 Send signal sofia/internal/2995 at 192.168.100.33 [KILL]
> 2011-04-08 10:09:27.679863 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:27.691976 [DEBUG] mod_conference.c:2810 Channel leaving conference, cause: NORMAL_CLEARING
> 2011-04-08 10:09:27.692990 [DEBUG] mod_conference.c:5986 sofia/internal/2995 at 192.168.100.33 skip receive message [UNBRIDGE] (channel is hungup already)
> 2011-04-08 10:09:27.692990 [DEBUG] switch_core_codec.c:141 sofia/internal/2995 at 192.168.100.33 Restore previous codec G722:9.
> 2011-04-08 10:09:27.692990 [DEBUG] switch_core_session.c:2060 sofia/internal/2995 at 192.168.100.33 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
> 2011-04-08 10:09:27.692990 [DEBUG] switch_core_state_machine.c:371 (sofia/internal/2995 at 192.168.100.33) State EXECUTE going to sleep
> 2011-04-08 10:09:27.692990 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_HANGUP
> 2011-04-08 10:09:27.692990 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/2995 at 192.168.100.33) State HANGUP
> 2011-04-08 10:09:27.692990 [DEBUG] mod_sofia.c:451 sofia/internal/2995 at 192.168.100.33 Overriding SIP cause 480 with 200 from the other leg
> 2011-04-08 10:09:27.692990 [DEBUG] mod_sofia.c:457 Channel sofia/internal/2995 at 192.168.100.33 hanging up, cause: NORMAL_CLEARING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:46 sofia/internal/2995 at 192.168.100.33 Standard HANGUP, cause: NORMAL_CLEARING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/2995 at 192.168.100.33) State HANGUP going to sleep
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:356 (sofia/internal/2995 at 192.168.100.33) State Change CS_HANGUP -> CS_REPORTING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_REPORTING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/2995 at 192.168.100.33) State REPORTING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:53 sofia/internal/2995 at 192.168.100.33 Standard REPORTING, cause: NORMAL_CLEARING
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:625 (sofia/internal/2995 at 192.168.100.33) State REPORTING going to sleep
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_state_machine.c:350 (sofia/internal/2995 at 192.168.100.33) State Change CS_REPORTING -> CS_DESTROY
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/2995 at 192.168.100.33 [BREAK]
> 2011-04-08 10:09:27.693998 [DEBUG] switch_core_session.c:1288 Session 111 (sofia/internal/2995 at 192.168.100.33) Locked, Waiting on external entities
> 2011-04-08 10:09:27.693998 [NOTICE] switch_core_session.c:1306 Session 111 (sofia/internal/2995 at 192.168.100.33) Ended
> 2011-04-08 10:09:27.693998 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/2995 at 192.168.100.33 [CS_DESTROY]
> 2011-04-08 10:09:27.695091 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/2995 at 192.168.100.33) Callstate Change HANGUP -> DOWN
> 2011-04-08 10:09:27.695091 [DEBUG] switch_core_state_machine.c:457 (sofia/internal/2995 at 192.168.100.33) Running State Change CS_DESTROY
> 2011-04-08 10:09:27.695091 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/2995 at 192.168.100.33) State DESTROY
> 2011-04-08 10:09:27.695091 [DEBUG] mod_sofia.c:362 sofia/internal/2995 at 192.168.100.33 SOFIA DESTROY
> 2011-04-08 10:09:27.695091 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2995 at 192.168.100.33 Standard DESTROY
> 2011-04-08 10:09:27.695091 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/2995 at 192.168.100.33) State DESTROY going to sleep
> 2011-04-08 10:09:27.741861 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:27.741861 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:27.741861 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (2 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:27]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:27 GMT]
> Event-Date-Timestamp: [1302271767692990]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_del_member]
> Event-Calling-Line-Number: [890]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (2 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [122]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> call-direction: [inbound]
>
>
> 2011-04-08 10:09:27.742874 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:27.742874 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:27.742874 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Active (2 callers)','','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='6c33a351-1842-4e45-9bfb-89249c44a8c6' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:27]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:27 GMT]
> Event-Date-Timestamp: [1302271767692990]
> Event-Calling-File: [mod_conference.c]
> Event-Calling-Function: [conference_del_member]
> Event-Calling-Line-Number: [890]
> proto: [conf]
> login: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> from: [6c33a351-1842-4e45-9bfb-89249c44a8c6 at 192.168.100.33]
> status: [Active (2 callers)]
> event_type: [presence]
> alt_event_type: [dialog]
> event_count: [122]
> unique-id: [6c33a351-1842-4e45-9bfb-89249c44a8c6]
> channel-state: [CS_ROUTING]
> answer-state: [confirmed]
> call-direction: [inbound]
>
>
> 2011-04-08 10:09:27.742874 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:27.742874 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:27.742874 [WARNING] sofia_presence.c:781 external is passive, skipping
> 2011-04-08 10:09:27.743881 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:27.743881 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Available','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal-ipv6' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:27]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:27 GMT]
> Event-Date-Timestamp: [1302271767692990]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_HANGUP]
> Channel-Call-State: [HANGUP]
> Channel-State-Number: [10]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [hangup]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [1302271744433706]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_HANGUP]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [idle]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:27.744888 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal-ipv6)
> 2011-04-08 10:09:27.745897 [INFO] sofia_presence.c:862 IN START_PRESENCE_SQL (internal)
> 2011-04-08 10:09:27.745897 [ERR] sofia_presence.c:871 DUMP PRESENCE SQL:
> select distinct sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_host,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscriptions.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscriptions.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subscriptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,'Available','unknown','192.168.100.33',sip_presence.status,sip_presence.rpid,sip_presence.open_closed,'','',sip_subscriptions.version, '2995 at 192.168.100.33' from sip_subscriptions left join sip_presence on (sip_subscriptions.sub_to_user=sip_presence.sip_user and sip_subscriptions.sub_to_host=sip_presence.sip_host and sip_subscriptions.profile_name=sip_presence.profile_name) where sip_subscriptions.version > -1 and sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and sub_to_user='2995' and (sub_to_host='192.168.100.33' or presence_hosts like '%192.168.100.33%') and (sip_subscriptions.profile_name = 'internal' or sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)
> EVENT DUMP:
> Event-Name: [PRESENCE_IN]
> Core-UUID: [4ff974b0-d64a-4319-8969-9781b791d838]
> FreeSWITCH-Hostname: [testsrv1]
> FreeSWITCH-IPv4: [192.168.100.33]
> FreeSWITCH-IPv6: [::1]
> Event-Date-Local: [2011-04-08 10:09:27]
> Event-Date-GMT: [Fri, 08 Apr 2011 14:09:27 GMT]
> Event-Date-Timestamp: [1302271767692990]
> Event-Calling-File: [switch_channel.c]
> Event-Calling-Function: [switch_channel_perform_presence]
> Event-Calling-Line-Number: [585]
> Channel-State: [CS_HANGUP]
> Channel-Call-State: [HANGUP]
> Channel-State-Number: [10]
> Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Channel-Presence-ID: [2995 at 192.168.100.33]
> Answer-State: [hangup]
> Channel-Read-Codec-Name: [G722]
> Channel-Read-Codec-Rate: [16000]
> Channel-Read-Codec-Bit-Rate: [64000]
> Channel-Write-Codec-Name: [G722]
> Channel-Write-Codec-Rate: [16000]
> Channel-Write-Codec-Bit-Rate: [64000]
> Caller-Direction: [inbound]
> Caller-Username: [2995]
> Caller-Dialplan: [inline]
> Caller-Caller-ID-Name: [Gourav Vohra]
> Caller-Caller-ID-Number: [2995]
> Caller-Network-Addr: [192.168.100.75]
> Caller-ANI: [2995]
> Caller-Unique-ID: [818ddd7f-99e2-4d22-b7ae-e62b6fdbfda4]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/internal/2995 at 192.168.100.33]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1302271744429760]
> Caller-Channel-Created-Time: [1302271744429760]
> Caller-Channel-Answered-Time: [1302271744433706]
> Caller-Channel-Progress-Time: [0]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> proto: [src/switch_channel.c]
> login: [src/switch_channel.c]
> from: [2995 at 192.168.100.33]
> rpid: [unknown]
> status: [CS_HANGUP]
> event_type: [presence]
> alt_event_type: [dialog]
> presence-call-info-state: [idle]
> presence-call-info: [appearance-index=1]
> presence-call-direction: [inbound]
> event_count: [2]
> Presence-Calling-File: [src/switch_channel.c]
> Presence-Calling-Function: [switch_channel_perform_set_running_state]
> Presence-Calling-Line: [1660]
>
>
> 2011-04-08 10:09:27.745897 [INFO] sofia_presence.c:890 IN END_PRESENCE_SQL (internal)
> 2011-04-08 10:09:27.745897 [WARNING] sofia_presence.c:774 192.168.100.33 is an alias, skipping
> 2011-04-08 10:09:33.381769 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/2995.csv
> 2011-04-08 10:09:33.381769 [NOTICE] mod_cdr_csv.c:123 Rotated CDR logfile /usr/local/freeswitch/log/cdr-csv/Master.csv
>
>
>
> _______________________________________________
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
More information about the FreeSWITCH-users
mailing list