I am trying to send 2 currently connected channels into a ConfBridge. the dialplan flow is : a channel enters the dialplan which executes an AGI script which returns a conference number and puts the channel into the ConfBridge, the AGI also triggers another application to send an AMI redirect to an existing call to be redirected into that conference. It works most of the time, however it when it fails, it hangs up on one of the pre-existing callers.
Asterisk Version 1.8.9.0
in this debug output of one that failed:
SIP/CO999x1002.1-00000196 and SIP/CO999x1003.1-00000195 are in a conversation (bridged)
SIP/CO999x1001.1-0000019b dials a “2” to barge in and create a ConfBridge
SIP/CO999x1001.1-0000019b is put into the Conference
SIP/CO999x1002.1-00000196 is hung up unexpectedly
SIP/CO999x1003.1-00000195 is put into the Conference
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘GotoIf’
– Executing [2@phones:4] GotoIf(“SIP/CO999x1001.1-0000019b”, “0?13”) in new stack
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 4
Application: GotoIf
AppData: 0?13
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 4
Application: GotoIf
AppData: 0?13
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:10150 pbx_builtin_gotoif: Not taking any branch
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4126 pbx_substitute_variables_helper_full: Expression result is ‘0’
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘GotoIf’
– Executing [2@phones:5] GotoIf(“SIP/CO999x1001.1-0000019b”, “0?16”) in new stack
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 5
Application: GotoIf
AppData: 0?16
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 5
Application: GotoIf
AppData: 0?16
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:10150 pbx_builtin_gotoif: Not taking any branch
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4126 pbx_substitute_variables_helper_full: Expression result is ‘0’
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘GotoIf’
– Executing [2@phones:6] GotoIf(“SIP/CO999x1001.1-0000019b”, “0?21”) in new stack
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 6
Application: GotoIf
AppData: 0?21
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 6
Application: GotoIf
AppData: 0?21
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:10150 pbx_builtin_gotoif: Not taking any branch
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4126 pbx_substitute_variables_helper_full: Expression result is ‘453’
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘UserEvent’
– Executing [2@phones:7] UserEvent(“SIP/CO999x1001.1-0000019b”, “ConfJoin,Conf: 453,Channel: SIP/CO999x1001.1-0000019b,Meetme: 245597274371231453,Uniqueid: 1329285082.1789”) in new stack
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 7
Application: UserEvent
AppData: ConfJoin,Conf: 453,Channel: SIP/CO999x1001.1-0000019b,Meetme: 245597274371231453,Uniqueid: 1329285082.1789
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 7
Application: UserEvent
AppData: ConfJoin,Conf: 453,Channel: SIP/CO999x1001.1-0000019b,Meetme: 245597274371231453,Uniqueid: 1329285082.1789
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: UserEvent
Privilege: user,all
UserEvent: ConfJoin
Conf: 453
Channel: SIP/CO999x1001.1-0000019b
Meetme: 245597274371231453
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: UserEvent
Privilege: user,all
UserEvent: ConfJoin
Conf: 453
Channel: SIP/CO999x1001.1-0000019b
Meetme: 245597274371231453
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘ConfBridge’
– Executing [2@phones:8] ConfBridge(“SIP/CO999x1001.1-0000019b”, “245597274371231453,1q”) in new stack
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4740 process_message: Running action ‘Redirect’
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 8
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[25188]: app_confbridge.c:424 join_conference_bridge: Trying to find conference bridge ‘245597274371231453’
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology simple_bridge has capabilities 34 and we want 4
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:397 find_best_technology: Bridge technology simple_bridge does not have the capabilities we need.
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology multiplexed_bridge has capabilities 2 and we want 4
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:397 find_best_technology: Bridge technology multiplexed_bridge does not have the capabilities we need.
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology softmix has capabilities 52 and we want 4
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:412 find_best_technology: Chose bridge technology softmix
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:490 ast_bridge_new: Giving bridge technology softmix the bridge structure 0x17221958 to setup
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:524 ast_bridge_destroy: Telling all channels in bridge 0x17221958 to end and leave the party
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:424 destroy_bridge: Actually destroying bridge 0x17221958, nobody wants it anymore
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:428 destroy_bridge: Giving bridge technology softmix the bridge structure 0x17221958 to destroy
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology simple_bridge has capabilities 34 and we want 2
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newchannel
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum:
CallerIDName:
AccountCode:
Exten: 453
Context: meetme-build
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5919 __ast_channel_masquerade: Planning to masquerade channel SIP/CO999x1003.1-00000195 into the structure of AsyncGoto/SIP/CO999x1003.1-00000195
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5929 __ast_channel_masquerade: Done planning to masquerade channel SIP/CO999x1003.1-00000195 into the structure of AsyncGoto/SIP/CO999x1003.1-00000195
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/CO999x1003.1-00000195
CloneState: Up
Original: AsyncGoto/SIP/CO999x1003.1-00000195
OriginalState: Up
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology multiplexed_bridge has capabilities 2 and we want 2
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology softmix has capabilities 52 and we want 2
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:397 find_best_technology: Bridge technology softmix does not have the capabilities we need.
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:412 find_best_technology: Chose bridge technology multiplexed_bridge
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:490 ast_bridge_new: Giving bridge technology multiplexed_bridge the bridge structure 0x2aaacc09e818 to setup
[Feb 14 22:51:22] DEBUG[25188]: bridge_multiplexed.c:145 multiplexed_bridge_create: Created multiplexed thread ‘0x2aaacc09e8e8’ for bridge ‘0x2aaacc09e818’
[Feb 14 22:51:22] DEBUG[25188]: app_confbridge.c:465 join_conference_bridge: Created conference bridge ‘245597274371231453’ and linked to container ‘0x16ffa928’
[Feb 14 22:51:22] DEBUG[25188]: devicestate.c:438 devstate_event: device ‘confbridge:245597274371231453’ state ‘2’
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:875 bridge_channel_join: Joining bridge channel 0x413fe620 to bridge 0x2aaacc09e818
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:159 bridge_array_add: Added channel SIP/CO999x1001.1-0000019b(0x2aaacc0a16a8) to bridge array on 0x2aaacc09e818, new count is 1
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:600 smart_bridge_operation: Bridge 0x2aaacc09e818 channel count (1) is within limits for bridge technology multiplexed_bridge, not performing smart bridge operation.
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:560 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1001.1-0000019b already has read format ulaw
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:580 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1001.1-0000019b already has write format ulaw
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:907 bridge_channel_join: Giving bridge technology multiplexed_bridge notification that 0x413fe620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:22] DEBUG[25188]: bridge_multiplexed.c:321 multiplexed_bridge_join: Adding channel ‘SIP/CO999x1001.1-0000019b’ to multiplexed thread ‘0x2aaacc09e8e8’ for monitoring
[Feb 14 22:51:22] DEBUG[25188]: bridging.c:747 bridge_channel_join_singlethreaded: Going into a single threaded signal wait for bridge channel 0x413fe620 of bridge 0x2aaacc09e818
[Feb 14 22:51:22] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘confbridge:245597274371231453’ changed to state ‘2’ (In use) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to write format slin
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to read format slin
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6713 ast_do_masquerade: Putting channel SIP/CO999x1003.1-00000195 in slin/slin formats
[Feb 14 22:51:22] DEBUG[26185]: chan_sip.c:6727 sip_fixup: SIP Fixup: New owner for dialogue MGJjN2YyYWZhMGFlYTUxMWJiZjMyNTM5YjEzNWNkNzg.: SIP/CO999x1003.1-00000195 (Old parent: AsyncGoto/SIP/CO999x1003.1-00000195)
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6758 ast_do_masquerade: Released clone lock on ‘AsyncGoto/SIP/CO999x1003.1-00000195’
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6766 ast_do_masquerade: Done Masquerading SIP/CO999x1003.1-00000195 (6)
[Feb 14 22:51:22] DEBUG[26185]: res_rtp_asterisk.c:788 ast_rtp_change_source: Changing ssrc from 950989757 to 865261501 due to a source change
[Feb 14 22:51:22] DEBUG[24989]: rtp_engine.c:841 local_bridge_loop: rtp-engine-local-bridge: Oooh, formats changed, backing out
[Feb 14 22:51:22] DEBUG[24989]: channel.c:7062 ast_generic_bridge: Didn’t get a frame from channel: AsyncGoto/SIP/CO999x1003.1-00000195
[Feb 14 22:51:22] DEBUG[25209]: pbx.c:4230 pbx_extension_helper: Launching ‘Answer’
– Executing [453@meetme-build:1] Answer(“SIP/CO999x1003.1-00000195”, “”) in new stack
[Feb 14 22:51:22] DEBUG[25209]: pbx.c:4230 pbx_extension_helper: Launching ‘Wait’
– Executing [453@meetme-build:2] Wait(“SIP/CO999x1003.1-00000195”, “0.1”) in new stack
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5919 __ast_channel_masquerade: Planning to masquerade channel SIP/CO999x1002.1-00000196 into the structure of AsyncGoto/SIP/CO999x1002.1-00000196
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5929 __ast_channel_masquerade: Done planning to masquerade channel SIP/CO999x1002.1-00000196 into the structure of AsyncGoto/SIP/CO999x1002.1-00000196
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
Newname: SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Newname: SIP/CO999x1003.1-00000195
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
Newname: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Variable: MACRO_DEPTH
Value: 0
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: NewCallerid
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
CallerIDNum: 3036792393
CallerIDName: Dispatch
Uniqueid: 1329285082.1790
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Variable: BRIDGEPEER
Value: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Variable: BRIDGEPEER
Value: SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Variable: BRIDGEPVTCALLID
Value: 5409c088499e96692f7f6c8c196fac9c@192.168.57.11:5060
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newchannel
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum:
CallerIDName:
AccountCode:
Exten: 453
Context: meetme-build
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 1
Application: Answer
AppData:
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 2
Application: Wait
AppData: 0.1
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/CO999x1002.1-00000196
CloneState: Up
Original: AsyncGoto/SIP/CO999x1002.1-00000196
OriginalState: Up
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
Newname: SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Newname: SIP/CO999x1002.1-00000196
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
Newname: AsyncGoto/SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Variable: MACRO_DEPTH
Value: 0
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[25208]: bridge_multiplexed.c:211 multiplexed_thread_function: Starting actual thread for multiplexed thread ‘0x2aaacc09e8e8’
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: NewCallerid
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
CallerIDNum: 3036792393
CallerIDName: Dispatch
Uniqueid: 1329285082.1791
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1002.1-00000196 to write format slin
[Feb 14 22:51:22] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1002.1-00000196 to read format slin
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6713 ast_do_masquerade: Putting channel SIP/CO999x1002.1-00000196 in slin/slin formats
[Feb 14 22:51:22] DEBUG[26185]: chan_sip.c:6727 sip_fixup: SIP Fixup: New owner for dialogue 5409c088499e96692f7f6c8c196fac9c@192.168.57.11:5060: SIP/CO999x1002.1-00000196 (Old parent: AsyncGoto/SIP/CO999x1002.1-00000196)
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6758 ast_do_masquerade: Released clone lock on ‘AsyncGoto/SIP/CO999x1002.1-00000196’
[Feb 14 22:51:22] DEBUG[26185]: channel.c:6766 ast_do_masquerade: Done Masquerading SIP/CO999x1002.1-00000196 (6)
[Feb 14 22:51:22] DEBUG[26185]: res_rtp_asterisk.c:788 ast_rtp_change_source: Changing ssrc from 1212617058 to 1963563362 due to a source change
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Unlink
Privilege: call,all
Channel1: AsyncGoto/SIP/CO999x1002.1-00000196
Channel2: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid1: 1329285081.1788
Uniqueid2: 1329285081.1787
CallerID1:
CallerID2:
[Feb 14 22:51:22] DEBUG[24989]: channel.c:7521 ast_channel_bridge: Bridge stops bridging channels AsyncGoto/SIP/CO999x1002.1-00000196 and AsyncGoto/SIP/CO999x1003.1-00000195
[Feb 14 22:51:22] DEBUG[24989]: channel.c:2680 ast_softhangup_nolock: Soft-Hanging up channel ‘AsyncGoto/SIP/CO999x1002.1-00000196’
[Feb 14 22:51:22] DEBUG[24989]: pbx.c:4230 pbx_extension_helper: Launching ‘NoOp’
– Executing [h@meetme-build:1] NoOp(“AsyncGoto/SIP/CO999x1002.1-00000196”, “meetme-build”) in new stack
[Feb 14 22:51:22] DEBUG[24989]: pbx.c:4230 pbx_extension_helper: Launching ‘Hangup’
– Executing [h@meetme-build:2] Hangup(“AsyncGoto/SIP/CO999x1002.1-00000196”, “”) in new stack
[Feb 14 22:51:22] DEBUG[24989]: features.c:4344 ast_bridge_call: Spawn extension (meetme-build,h,2) exited non-zero on ‘AsyncGoto/SIP/CO999x1002.1-00000196’
== Spawn extension (meetme-build, h, 2) exited non-zero on ‘AsyncGoto/SIP/CO999x1002.1-00000196’
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: h
Priority: 1
Application: NoOp
AppData: meetme-build
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: h
Priority: 2
Application: Hangup
AppData:
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[20627]: chan_sip.c:14041 parse_register_contact: Store REGISTER’s src-IP:port for call routing.
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: PeerStatus
Privilege: system,all
ChannelType: SIP
Peer: SIP/CO999x1002.1
PeerStatus: Registered
Address: 192.168.57.102:5060
[Feb 14 22:51:22] DEBUG[25210]: pbx.c:4230 pbx_extension_helper: Launching ‘Answer’
– Executing [453@meetme-build:1] Answer(“SIP/CO999x1002.1-00000196”, “”) in new stack
[Feb 14 22:51:22] DEBUG[25210]: pbx.c:4230 pbx_extension_helper: Launching ‘Wait’
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 1
Application: Answer
AppData:
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[24989]: channel.c:2831 ast_hangup: Hanging up zombie ‘AsyncGoto/SIP/CO999x1002.1-00000196’
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
CallerIDNum:
CallerIDName:
ConnectedLineNum:
ConnectedLineName:
Cause: 16
Cause-txt: Normal Clearing
[Feb 14 22:51:22] DEBUG[24989]: channel.c:2831 ast_hangup: Hanging up zombie ‘AsyncGoto/SIP/CO999x1003.1-00000195’
– Executing [453@meetme-build:2] Wait(“SIP/CO999x1002.1-00000196”, “0.1”) in new stack
[Feb 14 22:51:22] DEBUG[20627]: chan_sip.c:3334 __sip_xmit: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for 192.168.57.102:5060
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
CallerIDNum:
CallerIDName:
ConnectedLineNum:
ConnectedLineName:
Cause: 0
Cause-txt: Unknown
[Feb 14 22:51:22] DEBUG[20584]: chan_sip.c:26353 sip_devicestate: Checking device state for peer CO999x1002.1
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:458 do_state_change: Changing state for SIP/CO999x1002.1 - state 1 (Not in use)
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:438 devstate_event: device ‘SIP/CO999x1002.1’ state ‘1’
[Feb 14 22:51:22] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘SIP/CO999x1002.1’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:22] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 2
Application: Wait
AppData: 0.1
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: 2
Priority: 8
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1789
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newchannel
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum:
CallerIDName:
AccountCode:
Exten: 453
Context: meetme-build
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/CO999x1003.1-00000195
CloneState: Up
Original: AsyncGoto/SIP/CO999x1003.1-00000195
OriginalState: Up
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
Newname: SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Newname: SIP/CO999x1003.1-00000195
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
Newname: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Variable: MACRO_DEPTH
Value: 0
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: NewCallerid
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
CallerIDNum: 3036792393
CallerIDName: Dispatch
Uniqueid: 1329285082.1790
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Variable: BRIDGEPEER
Value: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Variable: BRIDGEPEER
Value: SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Variable: BRIDGEPVTCALLID
Value: 5409c088499e96692f7f6c8c196fac9c@192.168.57.11:5060
Uniqueid: 1329285081.1787
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newchannel
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum:
CallerIDName:
AccountCode:
Exten: 453
Context: meetme-build
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 1
Application: Answer
AppData:
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 2
Application: Wait
AppData: 0.1
Uniqueid: 1329285082.1790
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/CO999x1002.1-00000196
CloneState: Up
Original: AsyncGoto/SIP/CO999x1002.1-00000196
OriginalState: Up
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
Newname: SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Newname: SIP/CO999x1002.1-00000196
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
Newname: AsyncGoto/SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Variable: MACRO_DEPTH
Value: 0
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: NewCallerid
Privilege: call,all
Channel: SIP/CO999x1002.1-00000196
CallerIDNum: 3036792393
CallerIDName: Dispatch
Uniqueid: 1329285082.1791
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Unlink
Privilege: call,all
Channel1: AsyncGoto/SIP/CO999x1002.1-00000196
Channel2: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid1: 1329285081.1788
Uniqueid2: 1329285081.1787
CallerID1:
CallerID2:
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: h
Priority: 1
Application: NoOp
AppData: meetme-build
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: h
Priority: 2
Application: Hangup
AppData:
Uniqueid: 1329285081.1788
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: PeerStatus
Privilege: system,all
ChannelType: SIP
Peer: SIP/CO999x1002.1
PeerStatus: Registered
Address: 192.168.57.102:5060
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 1
Application: Answer
AppData:
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1002.1-00000196
Uniqueid: 1329285081.1788
CallerIDNum:
CallerIDName:
ConnectedLineNum:
ConnectedLineName:
Cause: 16
Cause-txt: Normal Clearing
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: AsyncGoto/SIP/CO999x1003.1-00000195
Uniqueid: 1329285081.1787
CallerIDNum:
CallerIDName:
ConnectedLineNum:
ConnectedLineName:
Cause: 0
Cause-txt: Unknown
[Feb 14 22:51:22] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 2
Application: Wait
AppData: 0.1
Uniqueid: 1329285082.1791
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:458 do_state_change: Changing state for AsyncGoto/SIP/CO999x1003.1 - state 4 (Invalid)
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:438 devstate_event: device ‘AsyncGoto/SIP/CO999x1003.1’ state ‘4’
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:458 do_state_change: Changing state for AsyncGoto/SIP/CO999x1002.1 - state 4 (Invalid)
[Feb 14 22:51:22] DEBUG[20584]: devicestate.c:438 devstate_event: device ‘AsyncGoto/SIP/CO999x1002.1’ state ‘4’
[Feb 14 22:51:22] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘AsyncGoto/SIP/CO999x1003.1’ changed to state ‘4’ (Invalid) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:22] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘AsyncGoto/SIP/CO999x1002.1’ changed to state ‘4’ (Invalid) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:23] DEBUG[25209]: pbx.c:4230 pbx_extension_helper: Launching ‘ConfBridge’
– Executing [453@meetme-build:3] ConfBridge(“SIP/CO999x1003.1-00000195”, “245597274371231453,1q”) in new stack
[Feb 14 22:51:23] DEBUG[25209]: app_confbridge.c:424 join_conference_bridge: Trying to find conference bridge ‘245597274371231453’
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:875 bridge_channel_join: Joining bridge channel 0x422a5620 to bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:159 bridge_array_add: Added channel SIP/CO999x1003.1-00000195(0x2aaacc09d118) to bridge array on 0x2aaacc09e818, new count is 2
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:600 smart_bridge_operation: Bridge 0x2aaacc09e818 channel count (2) is within limits for bridge technology multiplexed_bridge, not performing smart bridge operation.
[Feb 14 22:51:23] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 3
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1790
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:560 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1003.1-00000195 already has read format slin
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:580 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1003.1-00000195 already has write format slin
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:907 bridge_channel_join: Giving bridge technology multiplexed_bridge notification that 0x422a5620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25209]: bridge_multiplexed.c:321 multiplexed_bridge_join: Adding channel ‘SIP/CO999x1003.1-00000195’ to multiplexed thread ‘0x2aaacc09e8e8’ for monitoring
[Feb 14 22:51:23] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1003.1-00000195
Context: meetme-build
Extension: 453
Priority: 3
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1790
[Feb 14 22:51:23] DEBUG[25209]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to write format ulaw
[Feb 14 22:51:23] DEBUG[25209]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to read format ulaw
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:747 bridge_channel_join_singlethreaded: Going into a single threaded signal wait for bridge channel 0x422a5620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25208]: res_rtp_asterisk.c:1298 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Feb 14 22:51:23] DEBUG[25208]: res_rtp_asterisk.c:1329 ast_rtp_write: Created smoother: format: ulaw ms: 20 len: 160
[Feb 14 22:51:23] DEBUG[25210]: pbx.c:4230 pbx_extension_helper: Launching ‘ConfBridge’
– Executing [453@meetme-build:3] ConfBridge(“SIP/CO999x1002.1-00000196”, “245597274371231453,1q”) in new stack
[Feb 14 22:51:23] DEBUG[25210]: app_confbridge.c:424 join_conference_bridge: Trying to find conference bridge ‘245597274371231453’
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:875 bridge_channel_join: Joining bridge channel 0x42419620 to bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:159 bridge_array_add: Added channel SIP/CO999x1002.1-00000196(0x2aaac8002d78) to bridge array on 0x2aaacc09e818, new count is 3
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:391 find_best_technology: Bridge technology simple_bridge has capabilities 34 and we want 4
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:397 find_best_technology: Bridge technology simple_bridge does not have the capabilities we need.
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:391 find_best_technology: Bridge technology multiplexed_bridge has capabilities 2 and we want 4
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:397 find_best_technology: Bridge technology multiplexed_bridge does not have the capabilities we need.
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:391 find_best_technology: Bridge technology softmix has capabilities 52 and we want 4
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:412 find_best_technology: Chose bridge technology softmix
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:623 smart_bridge_operation: Performing smart bridge operation on bridge 0x2aaacc09e818, moving from bridge technology multiplexed_bridge to softmix
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:649 smart_bridge_operation: Giving bridge technology softmix the bridge structure 0x2aaacc09e818 to setup
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:664 smart_bridge_operation: Giving bridge technology multiplexed_bridge notification that 0x413fe620 is leaving bridge 0x424194d0 (really 0x2aaacc09e818)
[Feb 14 22:51:23] DEBUG[25210]: bridge_multiplexed.c:342 multiplexed_bridge_leave: Removing channel ‘SIP/CO999x1001.1-0000019b’ from multiplexed thread ‘0x2aaacc09e8e8’
[Feb 14 22:51:23] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 3
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1791
[Feb 14 22:51:23] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1002.1-00000196
Context: meetme-build
Extension: 453
Priority: 3
Application: ConfBridge
AppData: 245597274371231453,1q
Uniqueid: 1329285082.1791
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:551 bridge_make_compatible: Bridge technology softmix wants to read any of formats 0x40 (slin) but channel has ulaw
[Feb 14 22:51:23] DEBUG[25210]: channel.c:5150 set_format: Set channel SIP/CO999x1001.1-0000019b to read format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:558 bridge_make_compatible: Bridge 0x2aaacc09e818 put channel SIP/CO999x1001.1-0000019b into read format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:571 bridge_make_compatible: Bridge technology softmix wants to write any of formats 0x40 (slin) but channel has ulaw
[Feb 14 22:51:23] DEBUG[25210]: channel.c:5150 set_format: Set channel SIP/CO999x1001.1-0000019b to write format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:578 bridge_make_compatible: Bridge 0x2aaacc09e818 put channel SIP/CO999x1001.1-0000019b into write format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:675 smart_bridge_operation: Giving bridge technology softmix notification that 0x413fe620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:664 smart_bridge_operation: Giving bridge technology multiplexed_bridge notification that 0x422a5620 is leaving bridge 0x424194d0 (really 0x2aaacc09e818)
[Feb 14 22:51:23] DEBUG[25210]: bridge_multiplexed.c:342 multiplexed_bridge_leave: Removing channel ‘SIP/CO999x1003.1-00000195’ from multiplexed thread ‘0x2aaacc09e8e8’
[Feb 14 22:51:23] DEBUG[25208]: bridge_multiplexed.c:260 multiplexed_thread_function: Stopping actual thread for multiplexed thread ‘0x2aaacc09e8e8’
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:551 bridge_make_compatible: Bridge technology softmix wants to read any of formats 0x40 (slin) but channel has ulaw
[Feb 14 22:51:23] DEBUG[25210]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to read format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:558 bridge_make_compatible: Bridge 0x2aaacc09e818 put channel SIP/CO999x1003.1-00000195 into read format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:571 bridge_make_compatible: Bridge technology softmix wants to write any of formats 0x40 (slin) but channel has ulaw
[Feb 14 22:51:23] DEBUG[25210]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to write format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:578 bridge_make_compatible: Bridge 0x2aaacc09e818 put channel SIP/CO999x1003.1-00000195 into write format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:675 smart_bridge_operation: Giving bridge technology softmix notification that 0x422a5620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:690 smart_bridge_operation: Giving bridge technology multiplexed_bridge the bridge structure 0x424194d0 (really 0x2aaacc09e818) to destroy
[Feb 14 22:51:23] DEBUG[25210]: bridge_multiplexed.c:190 multiplexed_bridge_destroy: Unlinking multiplexed thread ‘0x2aaacc09e8e8’ since nobody is using it anymore
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:560 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1002.1-00000196 already has read format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:580 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1002.1-00000196 already has write format slin
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:907 bridge_channel_join: Giving bridge technology softmix notification that 0x42419620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:920 bridge_channel_join: Starting a bridge thread for bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x42419620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x422a5620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25188]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x413fe620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25227]: bridging.c:360 bridge_thread: Started bridge thread for 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25227]: bridging.c:367 bridge_thread: Launching bridge thread function 0x2aaab99a4050 for bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25188]: res_rtp_asterisk.c:1115 ast_rtp_raw_write: Difference is 3280, ms is 430
[Feb 14 22:51:23] DEBUG[25209]: res_rtp_asterisk.c:1115 ast_rtp_raw_write: Difference is 12616, ms is 1597
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x422a5620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25188]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x413fe620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: res_rtp_asterisk.c:1115 ast_rtp_raw_write: Difference is 15760, ms is 1990
Note about 250 multithreaded waitfor for bridge channel 0x42419620
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x42419620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25210]: res_rtp_asterisk.c:1732 ast_rtcp_read: Got RTCP report of 88 bytes
[Feb 14 22:51:23] DEBUG[25210]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x42419620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: RTCPReceived
Privilege: reporting,all
From: 192.168.57.102:2255
PT: 200(Sender Report)
ReceptionReports: 1
SenderSSRC: 0
FractionLost: 0
PacketsLost: 0
HighestSequence: 4740
SequenceNumberCycles: 0
IAJitter: 0
LastSR: 50777.893279859122372608
DLSR: 2.4200(sec)
RTT: 2(sec)
[Feb 14 22:51:23] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: RTCPReceived
Privilege: reporting,all
From: 192.168.57.102:2255
PT: 200(Sender Report)
ReceptionReports: 1
SenderSSRC: 0
FractionLost: 0
PacketsLost: 0
HighestSequence: 4740
SequenceNumberCycles: 0
IAJitter: 0
LastSR: 50777.893279859122372608
DLSR: 2.4200(sec)
RTT: 2(sec)
Note More Multiple instance of Going into a multithreaded waitfor for bridge channel
[Feb 14 22:51:23] DEBUG[25209]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x422a5620 of bridge 0x2aaacc09e818
[Feb 14 22:51:23] DEBUG[25188]: bridging.c:723 bridge_channel_join_multithreaded: Going into a multithreaded waitfor for bridge channel 0x413fe620 of bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[20627]: chan_sip.c:3076 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 195fbeca-ff95ae05-562bcc34@192.168.57.101
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOS
Value: ssrc=86212924;themssrc=729508452;lp=0;rxjitter=0.000060;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSJITTER
Value: minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOS
Value: ssrc=86212924;themssrc=729508452;lp=0;rxjitter=0.000060;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSJITTER
Value: minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[20627]: res_rtp_asterisk.c:2487 ast_rtp_remote_address_set: Setting RTCP address on RTP instance ‘0x172d1358’
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSLOSS
Value: minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSRTT
Value: minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSLOSS
Value: minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[20627]: chan_sip.c:23892 handle_request_bye: Received bye, issuing owner hangup
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Variable: RTPAUDIOQOSRTT
Value: minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[20627]: chan_sip.c:3334 __sip_xmit: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for 192.168.57.101:5060
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:951 bridge_channel_join: Giving bridge technology softmix notification that 0x413fe620 is leaving bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:195 bridge_array_remove: Removed channel 0x2aaacc0a16a8 from bridge array on 0x2aaacc09e818, new count is 2
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology simple_bridge has capabilities 34 and we want 2
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology multiplexed_bridge has capabilities 2 and we want 2
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:391 find_best_technology: Bridge technology softmix has capabilities 52 and we want 2
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:397 find_best_technology: Bridge technology softmix does not have the capabilities we need.
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:412 find_best_technology: Chose bridge technology multiplexed_bridge
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:623 smart_bridge_operation: Performing smart bridge operation on bridge 0x2aaacc09e818, moving from bridge technology softmix to multiplexed_bridge
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:634 smart_bridge_operation: Telling current bridge thread for bridge 0x2aaacc09e818 to stop
[Feb 14 22:51:24] DEBUG[25227]: bridging.c:373 bridge_thread: Ending bridge thread for 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:649 smart_bridge_operation: Giving bridge technology multiplexed_bridge the bridge structure 0x2aaacc09e818 to setup
[Feb 14 22:51:24] DEBUG[25188]: bridge_multiplexed.c:145 multiplexed_bridge_create: Created multiplexed thread ‘0x2aaacc0cda28’ for bridge ‘0x2aaacc09e818’
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:664 smart_bridge_operation: Giving bridge technology softmix notification that 0x422a5620 is leaving bridge 0x413fe4d0 (really 0x2aaacc09e818)
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:560 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1003.1-00000195 already has read format slin
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:580 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1003.1-00000195 already has write format slin
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:675 smart_bridge_operation: Giving bridge technology multiplexed_bridge notification that 0x422a5620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25188]: bridge_multiplexed.c:321 multiplexed_bridge_join: Adding channel ‘SIP/CO999x1003.1-00000195’ to multiplexed thread ‘0x2aaacc0cda28’ for monitoring
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:664 smart_bridge_operation: Giving bridge technology softmix notification that 0x42419620 is leaving bridge 0x413fe4d0 (really 0x2aaacc09e818)
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:560 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1002.1-00000196 already has read format slin
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:580 bridge_make_compatible: Bridge 0x2aaacc09e818 is happy that channel SIP/CO999x1002.1-00000196 already has write format slin
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:675 smart_bridge_operation: Giving bridge technology multiplexed_bridge notification that 0x42419620 is joining bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25188]: bridge_multiplexed.c:321 multiplexed_bridge_join: Adding channel ‘SIP/CO999x1002.1-00000196’ to multiplexed thread ‘0x2aaacc0cda28’ for monitoring
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:690 smart_bridge_operation: Giving bridge technology softmix the bridge structure 0x413fe4d0 (really 0x2aaacc09e818) to destroy
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:972 bridge_channel_join: Bridge is returning 0x413fe620 to read format ulaw(4)
[Feb 14 22:51:24] DEBUG[25210]: bridging.c:747 bridge_channel_join_singlethreaded: Going into a single threaded signal wait for bridge channel 0x42419620 of bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25209]: bridging.c:747 bridge_channel_join_singlethreaded: Going into a single threaded signal wait for bridge channel 0x422a5620 of bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25423]: bridge_multiplexed.c:211 multiplexed_thread_function: Starting actual thread for multiplexed thread ‘0x2aaacc0cda28’
[Feb 14 22:51:24] DEBUG[25188]: channel.c:5150 set_format: Set channel SIP/CO999x1001.1-0000019b to read format ulaw
[Feb 14 22:51:24] DEBUG[25188]: bridging.c:978 bridge_channel_join: Bridge is returning 0x413fe620 to write format ulaw(4)
[Feb 14 22:51:24] DEBUG[25188]: channel.c:5150 set_format: Set channel SIP/CO999x1001.1-0000019b to write format ulaw
[Feb 14 22:51:24] DEBUG[25188]: pbx.c:5022 __ast_pbx_run: Extension 2, priority 8 returned normally even though call was hung up
[Feb 14 22:51:24] DEBUG[25188]: channel.c:2680 ast_softhangup_nolock: Soft-Hanging up channel ‘SIP/CO999x1001.1-0000019b’
[Feb 14 22:51:24] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘NoOp’
– Executing [h@phones:1] NoOp(“SIP/CO999x1001.1-0000019b”, “phones”) in new stack
[Feb 14 22:51:24] DEBUG[25188]: pbx.c:4230 pbx_extension_helper: Launching ‘Hangup’
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: h
Priority: 1
Application: NoOp
AppData: phones
Uniqueid: 1329285082.1789
-- Executing [h@phones:2] Hangup("SIP/CO999x1001.1-0000019b", "") in new stack
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: h
Priority: 2
Application: Hangup
AppData:
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: h
Priority: 1
Application: NoOp
AppData: phones
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: SIP/CO999x1001.1-0000019b
Context: phones
Extension: h
Priority: 2
Application: Hangup
AppData:
Uniqueid: 1329285082.1789
[Feb 14 22:51:24] DEBUG[25188]: pbx.c:5225 __ast_pbx_run: Spawn extension (phones,h,2) exited non-zero on ‘SIP/CO999x1001.1-0000019b’
== Spawn extension (phones, h, 2) exited non-zero on ‘SIP/CO999x1001.1-0000019b’
[Feb 14 22:51:24] DEBUG[25188]: channel.c:2820 ast_hangup: Hanging up channel ‘SIP/CO999x1001.1-0000019b’
[Feb 14 22:51:24] DEBUG[25188]: chan_sip.c:6319 sip_hangup: Hanging up zombie call. Be scared.
[Feb 14 22:51:24] DEBUG[25188]: chan_sip.c:4295 sip_setoption: Set option TDD MODE, value: OFF on SIP/CO999x1001.1-0000019b
[Feb 14 22:51:24] DEBUG[25188]: chan_sip.c:4296 sip_setoption: Set option TDD MUTE, value: OFF on SIP/CO999x1001.1-0000019b
[Feb 14 22:51:24] DEBUG[25188]: res_rtp_asterisk.c:2487 ast_rtp_remote_address_set: Setting RTCP address on RTP instance ‘0x172d1358’
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: SIP/CO999x1001.1-0000019b
Uniqueid: 1329285082.1789
CallerIDNum: 3036792393
CallerIDName: Dispatch
ConnectedLineNum:
ConnectedLineName:
Cause: 16
Cause-txt: Normal Clearing
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4333 match_filter: Examining event:
Event: Hangup
Privilege: call,all
Channel: SIP/CO999x1001.1-0000019b
Uniqueid: 1329285082.1789
CallerIDNum: 3036792393
CallerIDName: Dispatch
ConnectedLineNum:
ConnectedLineName:
Cause: 16
Cause-txt: Normal Clearing
[Feb 14 22:51:24] DEBUG[20584]: chan_sip.c:26353 sip_devicestate: Checking device state for peer CO999x1001.1
[Feb 14 22:51:24] DEBUG[20584]: devicestate.c:458 do_state_change: Changing state for SIP/CO999x1001.1 - state 1 (Not in use)
[Feb 14 22:51:24] DEBUG[20584]: devicestate.c:438 devstate_event: device ‘SIP/CO999x1001.1’ state ‘1’
[Feb 14 22:51:24] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘SIP/CO999x1001.1’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:24] DEBUG[26185]: manager.c:4740 process_message: Running action ‘Bridge’
[Feb 14 22:51:24] DEBUG[26185]: channel.c:5919 __ast_channel_masquerade: Planning to masquerade channel SIP/CO999x1003.1-00000195 into the structure of Bridge/SIP/CO999x1003.1-00000195
[Feb 14 22:51:24] DEBUG[26185]: channel.c:5929 __ast_channel_masquerade: Done planning to masquerade channel SIP/CO999x1003.1-00000195 into the structure of Bridge/SIP/CO999x1003.1-00000195
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newchannel
Privilege: call,all
Channel: Bridge/SIP/CO999x1003.1-00000195
ChannelState: 0
ChannelStateDesc: Down
CallerIDNum:
CallerIDName:
AccountCode:
Exten:
Context:
Uniqueid: 1329285084.1792
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: MusicOnHold
Privilege: call,all
State: Stop
Channel: SIP/CO999x1003.1-00000195
UniqueID: 1329285082.1790
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Newstate
Privilege: call,all
Channel: Bridge/SIP/CO999x1003.1-00000195
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum:
CallerIDName:
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1329285084.1792
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Masquerade
Privilege: call,all
Clone: SIP/CO999x1003.1-00000195
CloneState: Up
Original: Bridge/SIP/CO999x1003.1-00000195
OriginalState: Up
[Feb 14 22:51:24] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to write format slin
[Feb 14 22:51:24] DEBUG[26185]: channel.c:5150 set_format: Set channel SIP/CO999x1003.1-00000195 to read format slin
[Feb 14 22:51:24] DEBUG[26185]: channel.c:6713 ast_do_masquerade: Putting channel SIP/CO999x1003.1-00000195 in slin/slin formats
[Feb 14 22:51:24] DEBUG[26185]: chan_sip.c:6727 sip_fixup: SIP Fixup: New owner for dialogue MGJjN2YyYWZhMGFlYTUxMWJiZjMyNTM5YjEzNWNkNzg.: SIP/CO999x1003.1-00000195 (Old parent: Bridge/SIP/CO999x1003.1-00000195)
[Feb 14 22:51:24] DEBUG[26185]: channel.c:6758 ast_do_masquerade: Released clone lock on ‘Bridge/SIP/CO999x1003.1-00000195’
[Feb 14 22:51:24] DEBUG[26185]: channel.c:6766 ast_do_masquerade: Done Masquerading SIP/CO999x1003.1-00000195 (6)
[Feb 14 22:51:24] DEBUG[26185]: res_rtp_asterisk.c:788 ast_rtp_change_source: Changing ssrc from 865261501 to 2041530440 due to a source change
[Feb 14 22:51:24] DEBUG[20584]: devicestate.c:458 do_state_change: Changing state for Bridge/SIP/CO999x1003.1 - state 2 (In use)
[Feb 14 22:51:24] DEBUG[20584]: devicestate.c:438 devstate_event: device ‘Bridge/SIP/CO999x1003.1’ state ‘2’
[Feb 14 22:51:24] DEBUG[25209]: bridging.c:951 bridge_channel_join: Giving bridge technology multiplexed_bridge notification that 0x422a5620 is leaving bridge 0x2aaacc09e818
[Feb 14 22:51:24] DEBUG[25209]: bridge_multiplexed.c:342 multiplexed_bridge_leave: Removing channel ‘Bridge/SIP/CO999x1003.1-00000195’ from multiplexed thread ‘0x2aaacc0cda28’
[Feb 14 22:51:24] DEBUG[25209]: bridging.c:195 bridge_array_remove: Removed channel 0x2aaacc09d118 from bridge array on 0x2aaacc09e818, new count is 1
[Feb 14 22:51:24] DEBUG[25209]: bridging.c:600 smart_bridge_operation: Bridge 0x2aaacc09e818 channel count (1) is within limits for bridge technology multiplexed_bridge, not performing smart bridge operation.
[Feb 14 22:51:24] DEBUG[25209]: pbx.c:5022 __ast_pbx_run: Extension 453, priority 3 returned normally even though call was hung up
[Feb 14 22:51:24] DEBUG[25209]: channel.c:2680 ast_softhangup_nolock: Soft-Hanging up channel ‘Bridge/SIP/CO999x1003.1-00000195’
[Feb 14 22:51:24] DEBUG[25209]: pbx.c:4230 pbx_extension_helper: Launching ‘NoOp’
– Executing [h@meetme-build:1] NoOp(“Bridge/SIP/CO999x1003.1-00000195”, “meetme-build”) in new stack
[Feb 14 22:51:24] DEBUG[20764]: app_queue.c:1487 handle_statechange: Device ‘Bridge/SIP/CO999x1003.1’ changed to state ‘2’ (In use) but we don’t care because they’re not a member of any queue.
[Feb 14 22:51:24] DEBUG[12539]: manager.c:4333 match_filter: Examining event:
Event: Rename
Privilege: call,all
Channel: SIP/CO999x1003.1-00000195
Newname: SIP/CO999x1003.1-00000195
Uniqueid: 1329285082.1790