Incoming call, no incoming audio
I think this is an IP address issue again. I'm able to successfully make an incoming call to the flash phone instance, but I cannot receive incoming audio, and after a few seconds the call is ended. I think i just need to figure out how to set the globalAddress for the default profile.
Here is a dump of the log for the call:
2010-12-10::16:56:11.679 DEBUG SIP 1584 Incoming call-leg 3072b28 was created
2010-12-10::16:56:11.679 DEBUG SIP 1584 Received Call msg type : -1
2010-12-10::16:56:11.679 DEBUG SIPLEG 1584 [LEG ID:0] - **callLegStateChangedEvHandler** State=RVSIP_CALL_LEG_STATE_OFFERING Reason=RVSIP_CALL_LEG_REASON_REMOTE_INVITING Dir=RVSIP_CALL_LEG_DIRECTION_INCOMING
2010-12-10::16:56:11.679 DEBUG SIP 1584 Trying to find matching sip profile in Sip Database
2010-12-10::16:56:11.679 DEBUG SIP 1584 Trying to find matching sip profile through TO hdr for username : 1000
2010-12-10::16:56:11.679 DEBUG SIP 1584 Trying to find matching sip profile through FROM hdr for username : 3109238566
2010-12-10::16:56:11.679 DEBUG SIP 1584 Trying to find matching sip profile through CONTACT hdr for username : 3109238566
2010-12-10::16:56:11.679 DEBUG SIP 1584 No matching profile found - using the default profile now
2010-12-10::16:56:11.679 DEBUG SIP 1584 getMGAddressToUse::localIp not set, Using MG Address=10.161.23.77
2010-12-10::16:56:11.680 DEBUG SIP 1584 Setting connection address to 10.161.23.77
2010-12-10::16:56:11.680 DEBUG SIP 1584 No Video session added in SDP
2010-12-10::16:56:11.680 DEBUG SIP 1584 -- Some fields from offer SDP message: --
2010-12-10::16:56:11.680 DEBUG SIP 1584 version field : 0
2010-12-10::16:56:11.680 DEBUG SIP 1584 session : Flash Media Gateway 2,0,0,72
2010-12-10::16:56:11.680 DEBUG SIP 1584 origin field:
2010-12-10::16:56:11.680 DEBUG SIP 1584 username : MG
2010-12-10::16:56:11.680 DEBUG SIP 1584 session id : 2630303
2010-12-10::16:56:11.680 DEBUG SIP 1584 version : 2630303
2010-12-10::16:56:11.680 DEBUG SIP 1584 address : 10.161.23.77
2010-12-10::16:56:11.680 DEBUG SIP 1584 connection field:
2010-12-10::16:56:11.680 DEBUG SIP 1584 nettype enum : 1
2010-12-10::16:56:11.680 DEBUG SIP 1584 addr type enum : 1
2010-12-10::16:56:11.680 DEBUG SIP 1584 address : 10.161.23.77
2010-12-10::16:56:11.680 DEBUG SIP 1584 Session Time 0:
2010-12-10::16:56:11.680 DEBUG SIP 1584 start : 0
2010-12-10::16:56:11.680 DEBUG SIP 1584 end : 0
2010-12-10::16:56:11.680 DEBUG SIP 1584 Media Descr 0:
2010-12-10::16:56:11.680 DEBUG SIP 1584 media type enum : 1
2010-12-10::16:56:11.680 DEBUG SIP 1584 port : 6000
2010-12-10::16:56:11.680 DEBUG SIP 1584 protocol enum : 1
2010-12-10::16:56:11.680 DEBUG SIP 1584 format 0 : 0
2010-12-10::16:56:11.680 DEBUG SIP 1584 format 1 : 111
2010-12-10::16:56:11.680 DEBUG SIP 1584 format 2 : 101
2010-12-10::16:56:11.681 DEBUG SIP 1584 codec PCMU was approved, payload type : 0
2010-12-10::16:56:11.681 DEBUG SIP 1584 codec telephone-event was approved, payload type : 101
2010-12-10::16:56:11.685 DEBUG CALLLEG 1584 [LEG ID:5] - Allocated new Call Leg(sip)
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 0
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - State ALLOC
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - State Change ALLOC -> START
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 1
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Call Leg START
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - State Change START -> RING
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 2
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Call Leg RINGing
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - State Change RING -> SENDRECV
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 3
2010-12-10::16:56:11.687 DEBUG SIPLEG 3184 [LEG ID:5] - sendrecvHandler called...
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Call Leg SENDRECV
2010-12-10::16:56:11.687 DEBUG CORE 3184 [LEG ID:5] - Processing <Context:rtmp>
2010-12-10::16:56:11.687 DEBUG CORE 3184 [LEG ID:5] - Testing condition <var:destNum, varData:1000> to <value:^1...$>
2010-12-10::16:56:11.687 DEBUG CORE 3184 num matches: 1
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - State Change SENDRECV -> EXEC
2010-12-10::16:56:11.687 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 4
2010-12-10::16:56:11.688 DEBUG SIPLEG 3184 [LEG ID:5] - executeHandler called...
2010-12-10::16:56:11.688 DEBUG CALLLEG 3184 [LEG ID:5] - Call Leg EXECUTE
2010-12-10::16:56:11.688 DEBUG CORE 3184 [LEG ID:5] - Processing <Context:rtmp>
2010-12-10::16:56:11.688 DEBUG CORE 3184 [LEG ID:5] - Testing condition <var:destNum, varData:1000> to <value:^1...$>
2010-12-10::16:56:11.688 DEBUG CORE 3184 num matches: 1
2010-12-10::16:56:11.688 DEBUG CALLLEG 3184 [LEG ID:5] - Execute bridge(rtmp|${destNum}@tff)
2010-12-10::16:56:11.688 DEBUG CALLLEG 3184 [LEG ID:5] - Expanded String bridge(rtmp|1000@tff) (ID:1)
2010-12-10::16:56:11.688 DEBUG SIPLEG 3184 [LEG ID:5] - Call in progress
2010-12-10::16:56:11.688 INFO CALLLEG 3184 [LEG ID:5] - Ringing
2010-12-10::16:56:11.688 DEBUG CALLLEG 3184 [LEG ID:6] - Allocated new Call Leg(rtmp)
2010-12-10::16:56:11.688 INFO RTMPLEG 3184 [LEG ID:6] - setting silence level to 6 for Leg
2010-12-10::16:56:11.688 INFO RTMPLEG 3184 [LEG ID:6] - setting silence Timeout to 500 for Leg
2010-12-10::16:56:11.692 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 0
2010-12-10::16:56:11.692 DEBUG CALLLEG 1716 [LEG ID:6] - State ALLOC
2010-12-10::16:56:11.694 DEBUG CALLLEG 1716 [LEG ID:6] - State Change ALLOC -> START
2010-12-10::16:56:11.694 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 1
2010-12-10::16:56:11.694 DEBUG RTMPLEG 1716 [LEG ID:6] - Inside start handler, creating stream
2010-12-10::16:56:11.694 DEBUG CALLLEG 1716 [LEG ID:6] - Call Leg START
2010-12-10::16:56:11.695 INFO RTMP 4748 Received onStatus <Success> code <status> classType <StreamCreated> description <> details
2010-12-10::16:56:11.698 INFO RTMP 4748 Received onStatus <NetStream.Publish.Start> code <status> classType <fmg/tff/3 is now published.> description <(null)> details
2010-12-10::16:56:11.953 INFO RTMP 4748 Received onStatus <Success> code <status> classType <StreamCreated> description <> details
2010-12-10::16:56:11.955 DEBUG CALLLEG 1716 [LEG ID:6] - State Change START -> RING
2010-12-10::16:56:11.955 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 2
2010-12-10::16:56:11.955 DEBUG CALLLEG 1716 [LEG ID:6] - Call Leg RINGing
2010-12-10::16:56:11.955 INFO RTMP 4748 Received onStatus <NetStream.Play.Reset> code <status> classType <Playing and resetting 1000.> description <(null)> details
2010-12-10::16:56:11.955 INFO RTMP 4748 Received onStatus <NetStream.Play.Start> code <status> classType <Started playing 1000.> description <(null)> details
2010-12-10::16:56:11.956 DEBUG SIPLEG 3184 [LEG ID:5] - Call in progress
2010-12-10::16:56:11.956 DEBUG CALLLEG 3184 [LEG ID:5] - Variable Value Set (bridgeUUID=b81c32d9-c9fb-432b-808c-ec7f9d4e8163)
2010-12-10::16:56:11.956 DEBUG CALLLEG 3184 [LEG ID:6] - Variable Value Set (bridgeUUID=a0313019-2d5e-4aae-ab34-a06acb804338)
2010-12-10::16:56:11.956 DEBUG SIPLEG 3184 [LEG ID:5] - Call ringing
2010-12-10::16:56:11.956 DEBUG CALLLEG 1716 [LEG ID:6] - State Change RING -> HOLD
2010-12-10::16:56:11.956 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 5
2010-12-10::16:56:11.956 DEBUG CALLLEG 1716 [LEG ID:6] - Call Leg HOLD
2010-12-10::16:56:12.001 INFO RTMP 4748 Received onStatus <NetStream.Play.PublishNotify> code <status> classType <1000 is now published.> description <(null)> details
2010-12-10::16:56:16.371 INFO CALLLEG 2568 [LEG ID:6] - Leg has been answered
2010-12-10::16:56:16.371 DEBUG CALLLEG 1716 [LEG ID:6] - State Change HOLD -> SENDRECV
2010-12-10::16:56:16.371 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 3
2010-12-10::16:56:16.372 DEBUG SIPLEG 3184 [LEG ID:5] - Call answered
2010-12-10::16:56:16.372 DEBUG SIP 3184 Port Allocated for Media transfer : 10006
2010-12-10::16:56:16.372 DEBUG SIP 3184 [LEG ID:5] - RTP Buffer size set to : 8192
2010-12-10::16:56:16.372 DEBUG SIP 3184 [LEG ID:5] - RTP session created successfully - localAddr - 10.161.23.77, localPort - 10006 remoteAddr - 216.52.221.141, remotePort - 16826
2010-12-10::16:56:16.373 DEBUG SIPLEG 3184 [LEG ID:5] - **callLegStateChangedEvHandler** State=RVSIP_CALL_LEG_STATE_ACCEPTED Reason=RVSIP_CALL_LEG_REASON_LOCAL_ACCEPTED Dir=RVSIP_CALL_LEG_DIRECTION_INCOMING
2010-12-10::16:56:16.373 INFO CALLLEG 3184 [LEG ID:5] - Leg has been answered
2010-12-10::16:56:16.433 DEBUG CALLLEG 1716 [LEG ID:5] - Write Buffer at 320 bytes to adjust 512->320
2010-12-10::16:56:48.387 DEBUG SIPLEG 1584 [LEG ID:5] - **callLegStateChangedEvHandler** State=RVSIP_CALL_LEG_STATE_DISCONNECTING Reason=RVSIP_CALL_LEG_REASON_LOCAL_DISCONNECTING Dir=RVSIP_CALL_LEG_DIRECTION_INCOMING
2010-12-10::16:56:48.414 DEBUG SIP 1584 Received Call msg type : 200
2010-12-10::16:56:48.415 DEBUG SIPLEG 1584 [LEG ID:5] - **callLegStateChangedEvHandler** State=RVSIP_CALL_LEG_STATE_DISCONNECTED Reason=RVSIP_CALL_LEG_REASON_DISCONNECTED Dir=RVSIP_CALL_LEG_DIRECTION_INCOMING
2010-12-10::16:56:48.415 DEBUG SIP 1584 Callleg disconnect cause is 200
2010-12-10::16:56:48.415 DEBUG CALLLEG 1584 [LEG ID:5] - State Change EXEC -> HANGUP
2010-12-10::16:56:48.415 DEBUG SIPLEG 1584 [LEG ID:0] - **callLegStateChangedEvHandler** State=RVSIP_CALL_LEG_STATE_TERMINATED Reason=RVSIP_CALL_LEG_REASON_CALL_TERMINATED Dir=RVSIP_CALL_LEG_DIRECTION_INCOMING
2010-12-10::16:56:48.415 DEBUG SIP 1584 [LEG ID:0] - Callleg disconnect cause was undefined, now set to 500 for reason RVSIP_CALL_LEG_REASON_CALL_TERMINATED
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:5] - Hangup Call (cause 200), from FMSMGAppNodesHelper.cpp:1153
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:6] - Bridging Completed for
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:6] - Hangup Call (cause 200), from FMSMGAppNodesHelper.cpp:960
2010-12-10::16:56:48.421 INFO CALLLEG 1716 [LEG ID:6] - Hangup [SENDRECV] [OK]
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:6] - Going For State 7
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:6] - Call Leg HANGUP, cause: OK
2010-12-10::16:56:48.421 INFO CALLLEG 1716 [LEG ID:6] - CallLeg Ended
2010-12-10::16:56:48.421 DEBUG CALLLEG 1716 [LEG ID:6] - Cleaning up Leg [HANGUP]
2010-12-10::16:56:48.423 INFO RTMP 4748 Received onStatus <NetStream.Unpublish.Success> code <status> classType <fmg/tff/3 is now unpublished.> description <(null)> details
2010-12-10::16:56:48.423 INFO RTMP 4748 Received onStatus <NetStream.Play.Stop> code <status> classType <Stopped playing 1000.> description <(null)> details
2010-12-10::16:56:48.424 DEBUG CALLLEG 3184 [LEG ID:6] - Hangup Call (cause 200), from FMSMGAppNodesHelper.cpp:1153
2010-12-10::16:56:48.424 DEBUG CALLLEG 3184 [LEG ID:5] - Bridging Completed for
2010-12-10::16:56:48.424 DEBUG CALLLEG 3184 [LEG ID:5] - Hangup Call (cause 200), from FMSMGAppNodesHelper.cpp:1251
2010-12-10::16:56:49.424 DEBUG CALLLEG 3184 [LEG ID:5] - App bridge(rtmp|1000@tff) Returns 0 (next ID:1)
2010-12-10::16:56:49.424 DEBUG CALLLEG 3184 [LEG ID:5] - Going For State 7
2010-12-10::16:56:49.424 DEBUG SIPLEG 3184 [LEG ID:5] - hangupHandler called...default
2010-12-10::16:56:49.424 DEBUG SIP 3184 Closing audio RTP session
2010-12-10::16:56:49.424 DEBUG SIP 3184 closed audio socket
2010-12-10::16:56:49.424 DEBUG SIP 3184 closed video socket
2010-12-10::16:56:49.424 DEBUG SIP 3184 Removing Call Leg Entry for UUID a0313019-2d5e-4aae-ab34-a06acb804338
2010-12-10::16:56:49.424 DEBUG SIP 3184 call reject failed, will try disconnecting it
2010-12-10::16:56:49.434 DEBUG SIP 3184 Disconnected call name : default
2010-12-10::16:56:49.434 DEBUG CALLLEG 3184 [LEG ID:5] - Call Leg HANGUP, cause: OK
2010-12-10::16:56:49.434 INFO CALLLEG 3184 [LEG ID:5] - CallLeg Ended
2010-12-10::16:56:49.434 DEBUG CALLLEG 3184 [LEG ID:5] - Cleaning up Leg [HANGUP]
2010-12-10::16:56:49.434 DEBUG SIPLEG 3184 [LEG ID:5] - deleting sip leg
2010-12-10::16:56:49.434 DEBUG SIP 3184 Closing audio RTP session
2010-12-10::16:56:49.434 DEBUG SIP 3184 closed audio socket
2010-12-10::16:56:49.434 DEBUG SIP 3184 closed video socket
2010-12-10::16:56:50.421 DEBUG RTMP 1716 [min-size, count]: [0,0],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,0], [9,0], [>=10,0]
2010-12-10::16:56:50.421 DEBUG RTMP 1716 [max-size, count]: [0,0],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,0], [9,0], [>=10,0]
2010-12-10::16:56:50.421 DEBUG RTMP 1716 [variation, count]: [0,0],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,0], [9,0], [>=10,0]
2010-12-10::16:56:50.421 DEBUG RTMP 1716 [min-size, count]: [0,2],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,0], [9,0], [>=10,0]
2010-12-10::16:56:50.422 DEBUG RTMP 1716 [max-size, count]: [0,0],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,1], [9,0], [>=10,1]
2010-12-10::16:56:50.422 DEBUG RTMP 1716 [variation, count]: [0,0],[1,0], [2,0], [3,0], [4,0], [5,0], [6,0], [7,0], [8,1], [9,0], [>=10,1]
my entire workflow.xml is this:
<WorkFlow>
<ContextList>
<Context name="sipGatewayContext">
<!-- call rtmp phone -->
<Condition variable="destNum" value="^.*$">
<AppNode sequence="1" app="bridge" args="rtmp|${destNum}@tff"/>
<AppNode sequence="2" app="hangup" args="null"/>
</Condition>
</Context>
<Context name="rtmp">
<!-- play moh.raw file and then hangup -->
<!-- call rtmp phone -->
<Condition variable="destNum" value="^1...$">
<AppNode sequence="1" app="bridge" args="rtmp|${destNum}@tff"/>
<AppNode sequence="2" app="hangup" args="null"/>
</Condition>
<!-- call made on SIP g/w with the destination number -->
<Condition variable="destNum" value="^.*$">
<AppNode sequence="1" app="bridge" args="sip|${destNum}@sipGateway"/>
<AppNode sequence="2" app="hangup" args="null"/>
</Condition>
</Context>
</ContextList>
</WorkFlow>
and I have 2 profiles in rtmp.xml:
<Profiles>
<!-- To enable using auto profileID if entry not found in any <Profile> tag below. -->
<!-- Default value is false -->
<EnableAutoProfile>true</EnableAutoProfile>
<!-- DefaultContext value will be used for all AutoProfiles. Default value is default -->
<DefaultContext>rtmp</DefaultContext>
<!-- The default passcode that FMG will use for all FMS apps for which no specific -->
<!-- is mentioned in xml configurations -->
<DefaultPassCode>passcode_default</DefaultPassCode>
<!-- Specifying list of RTMP <profile>, all fields are required, please refer -->
<!-- to configuration guide to know more about restrictions. -->
<Profile>
<!-- Unique profile ID accross rtmp plugin, This profileID can be used -->
<!-- in workflow.xml to specify call flows. -->
<ProfileID> profile_default </ProfileID>
<!-- FMS-Host, application & instance name are mandatory to specify an -->
<!-- application to which this profile ID is assigned -->
<Server> 184.72.XX.XXX </Server>
<Application> tff </Application>
<Instance> _definst_ </Instance>
<!-- the shared Passcode that FMG will use while connecting to this application instance -->
<!-- -->
<PassCode>passcode_default</PassCode>
<!-- Name of the workflow context (in workflow.xml or workflow script -->
<!-- in FMG) which will be applied to the calls originating from this profileID -->
<Context> rtmp </Context>
</Profile>
<Profile>
<!-- Unique profile ID accross rtmp plugin, This profileID can be used -->
<!-- in workflow.xml to specify call flows. -->
<ProfileID> tff </ProfileID>
<!-- FMS-Host, application & instance name are mandatory to specify an -->
<!-- application to which this profile ID is assigned -->
<Server> 184.72.XX.XXX </Server>
<Application> tff </Application>
<Instance> _definst_ </Instance>
<!-- the shared Passcode that FMG will use while connecting to this application instance -->
<!-- -->
<PassCode>passcode_default</PassCode>
<!-- Name of the workflow context (in workflow.xml or workflow script -->
<!-- in FMG) which will be applied to the calls originating from this profileID -->
<Context> rtmp </Context>
</Profile>
</Profiles>
