cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
Announcements
1935
Views
5
Helpful
16
Replies
Mihkel Laur
Beginner

TMS created conferences do not allow presentation for all devices

Hello, 

 
TMS version 15.4.4
CMS 2.2.6

I have a strange problem with conferences done through TMS. 

When a scheduled TMS conference starts, if a presentation is shared via SX20, no one can see it. 

 

However, if these clients (sx20, polycoms, s4b clients, webbridge clients) connect straight to CMS, there are no problems. 

Another anomaly is like this: 
TMS starts conference. Presentations cannot be seen. S4B or webbridge client shares screen for a moment. Everyone can see. After this, SX20 starts presentation and now everything works, everyone can see presentations...

 

In API, in the system profile I have one callLegProfile assigned and it should have all the necessary stuff to allow for presentations. <callLegProfile id="34b7eeed-25d3-47fb-b388-39b46226f3bd">
<defaultLayout>allEqual</defaultLayout>
<presentationContributionAllowed>true</presentationContributionAllowed>
<presentationViewingAllowed>true</presentationViewingAllowed>
<sipMediaEncryption>optional</sipMediaEncryption>
<telepresenceCallsAllowed>true</telepresenceCallsAllowed>
<sipPresentationChannelEnabled>true</sipPresentationChannelEnabled>
<bfcpMode>serverOnly</bfcpMode>
<recordingControlAllowed>true</recordingControlAllowed>
</callLegProfile>

 

And again, no problems whatsoever when avoiding TMS. 

 

Any ideas? 

 

Thanks

16 REPLIES 16
Joe Vallender
Participant

You might get a packet capture of the call setup and negotiation on one of the units that fails to establish the presentation.  This could show what is being requested and how it is responded to.

The <bfcpMode>serverOnly</bfcpMode> might be coming into play somehow.

I havent found any clues from the packet capture yet. 

 

Also, with bfcpMode, i tried deleting it and tried serverAndClient. Nothing changed. 

 

Still, when connecting without TMS, there are no problems. 

UPDATE

When conferencing through TMS, even other SX20 devices do not see presentations.

 

However, presentations with webbridge and S4B work fine. Also, as said before. When presented with S4B, webbridge, SX20 devices start presenting fine

UPDATE

 

If i set CMS Call settings Presentation video channel operations to ''prohibited'' then it is possible to see the presentation. However this is not a solution, it will just combine video and presentation to one stream. As we use ''all equal'' layout for conferences, this is not a usable option. 

 

Still havent found out why TMS messes up these conferences. 

In the packet captures of a good and bad call, how do the content channel parameters being negotiated differ?  Can you share the packet captures or portions showing the content channel negotiation?

What would be the best method for getting the packet capture? 

After Google/Bing, etc searching for 'Cisco SX20 packet capture', the Cisco Troubleshooting Guides for TC software explain how:

Packet capture

You can capture the traffic from using port spanning on your switch (or use a hub), or directly from the video system. The latter is in many cases easier, although there are some limitations. To capture directly from the system requires root access (see ”Appendix B – Gaining root access to your system” on page 33) and will use tcpdump to capture all information entering and leaving the system.

The advantage of this method is that, if you capture a complete call it can be opened in Wireshark and analyzed graphically.

 

Some TC version (not sure exactly but after TC 7.x Something) has root access disabled. So you can't perform packet capture from the root. 

 

On the web GUI under the diagnostic you have an option to run extended login. However you run it only for 3-10 minute i think.

 

regards,

Alok

A few UPDATES before is send the capture. 
CMS is now 2.2.7 and TMS 15.6.0 
All my codecs are 8.2.2 <-- I am unable to update these atm because there is some trouble with our contracts. I can get the latest CMS and TMS soft but not for the endpoints. The issue has been raised, but for now i cannot update them. 

So this is a call with presentation not working. 

I deleted some info regarding domain names and enpoint names. 

 

2017-09-11T12:16:07.800+03:00 a8 appl[1754]: 9034.03 Bfcp onHelloTimer
2017-09-11T12:16:07.800+03:00 a8 appl[1754]: 9034.03 Bfcp addTransaction (num transactions=1)
2017-09-11T12:16:07.800+03:00 a8 appl[1754]: 9034.03 Bfcp Transaction: Id 17 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:07.800+03:00 a8 appl[1754]: 9034.03 Bfcp execTransaction
2017-09-11T12:16:07.802+03:00 a8 appl[1754]: 9034.03 Bfcp Transaction: Id 17 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:07.802+03:00 a8 appl[1754]: 9034.03 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:07.818+03:00 a8 appl[1754]: 9034.05 Bfcp BFCP packet arrived (len=56)
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp BFCP session received data. Decoding ...
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp handleHelloAck
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp handleHelloAck: ack in session 2 after 0 attempts
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp deleteTransaction
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp removeTransaction
2017-09-11T12:16:07.819+03:00 a8 appl[1754]: 9034.05 Bfcp Transaction: Id 17 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:10.117+03:00 a8 appl[1754]: 9036.35 CuilApp User __remotetouchdevice__ about to execute command '/Video/Input/Source/SetActiveConnector ConnectorId: 2' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.119+03:00 a8 appl[1754]: 9036.35 CuilApp User __remotetouchdevice__ successfully executed command '/Video/Input/Source/SetActiveConnector ConnectorId: 2' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.120+03:00 a8 appl[1754]: 9036.35 CuilApp User __remotetouchdevice__ about to execute command '/Presentation/Start PresentationSource: 2 SendingMode: LocalRemote' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.121+03:00 a8 appl[1754]: 9036.35 CuilApp User __remotetouchdevice__ successfully executed command '/Presentation/Start PresentationSource: 2 SendingMode: LocalRemote' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.122+03:00 a8 appl[1754]: 9036.35 APPL_ConfCtrl PresentationServiceImpl::startSend instance=1 source=input[2] foucusMode=focused focusOrder=0 outputNumber=0
2017-09-11T12:16:10.122+03:00 a8 appl[1754]: 9036.36 APPL_ConfCtrl PresentationServiceImpl::selectAutoFocusOrder focusOrder=1
2017-09-11T12:16:10.122+03:00 a8 appl[1754]: 9036.36 APPL_ConfCtrl ConferenceSessionImpl::localTokenGrantReq (c=1)
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 APPL_ConfCtrl RemoteParticipantImpl::sendTokenMsg (p=2)RequireReq tokenId=2 tokenReqId=0 tokenHolderId=0
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp doBFCPFloorRequestReq
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp doFloorRequestReq
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp addTransaction (num transactions=1)
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp execTransaction
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST
2017-09-11T12:16:10.123+03:00 a8 appl[1754]: 9036.36 Bfcp startUdpRetransmitTimer Starting UDP timer for session 2
2017-09-11T12:16:10.124+03:00 a8 appl[1754]: 9036.36 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:10.127+03:00 a8 appl[1754]: 9036.36 CuilApp User __remotetouchdevice__ about to execute command '/UserInterface/OSD/Close Element: Menu' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.128+03:00 a8 appl[1754]: 9036.36 CuilApp User __remotetouchdevice__ successfully executed command '/UserInterface/OSD/Close Element: Menu' from ::ffff:172.16.15.16.
2017-09-11T12:16:10.132+03:00 a8 appl[1754]: 9036.36 Bfcp BFCP packet arrived (len=28)
2017-09-11T12:16:10.132+03:00 a8 appl[1754]: 9036.37 Bfcp BFCP session received data. Decoding ...
2017-09-11T12:16:10.132+03:00 a8 appl[1754]: 9036.37 Bfcp handleFloorRequestStatus
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp handleFloorRequestStatus: Incoming FLOOR_REQUEST_STATUS=BFCP_GRANTED, request_id=14
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp addTransaction (num transactions=2)
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_GRANTED
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp execTransaction
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_GRANTED
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp execTransaction BFCP_FLOOR_REQUEST already in progress
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp deleteTransaction
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp removeTransaction
2017-09-11T12:16:10.133+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST
2017-09-11T12:16:10.136+03:00 a8 appl[1754]: 9036.37 Bfcp execTransaction
2017-09-11T12:16:10.136+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_GRANTED
2017-09-11T12:16:10.138+03:00 a8 appl[1754]: 9036.37 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:10.138+03:00 a8 appl[1754]: 9036.37 Bfcp deleteTransaction
2017-09-11T12:16:10.138+03:00 a8 appl[1754]: 9036.37 Bfcp removeTransaction
2017-09-11T12:16:10.138+03:00 a8 appl[1754]: 9036.37 Bfcp Transaction: Id 18 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_GRANTED
2017-09-11T12:16:10.139+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl ConferenceSessionImpl::tokenOwnerChanged (c=1, newp=1, oldp=-1)(token=2)
2017-09-11T12:16:10.140+03:00 a8 appl[1754]: 9036.37 APPL_EventMonitor notifyEvent[0x45238ad4] 'PresentationTokenGranted confId=1 partId=1' from PresentationServiceImpl[0x45e685fc]
2017-09-11T12:16:10.140+03:00 a8 appl[1754]: 9036.37 APPL_EventMonitor ...sync eventUpdate[0x45238ad4] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:10.140+03:00 a8 appl[1754]: 9036.37 MainEvents I: PresentationTokenGranted() c=1 p=1
2017-09-11T12:16:10.140+03:00 a8 appl[1754]: 9036.37 APPL_EventMonitor ...sync eventUpdate[0x45238ad4] to LayoutTransitionPresentationStartImpl[0x45ec979c]
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl ResourceControllerImpl::requestToStartOutgoingPresentation
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl OperationExecutorImpl::createOperationSet set=RCI
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl OperationExecutorImpl::addOperation set=RCI oper=configureCapset_outgPresentation
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl OperationExecutorImpl::execute
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl OperationExecutorImpl::executeNextOperation oper=configureCapset_outgPresentation
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl OperationExecutorImpl::executeNextOperation oper=configureCapset_outgPresentation status=completed
2017-09-11T12:16:10.141+03:00 a8 appl[1754]: 9036.37 APPL_ConfCtrl ResourceControllerImpl::handleNewRequest proceed callAccessResult_ok
2017-09-11T12:16:10.142+03:00 a8 appl[1754]: 9036.37 APPL_EventMonitor notifyEvent[0x466acea8] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.142+03:00 a8 appl[1754]: 9036.37 APPL_EventMonitor notifyEvent[0x466d0278] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.142+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor notifyEvent[0x466d0278] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.144+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor notifyEvent[0x467e189c] 'PresentationStarted cause=0 confId=1 local=1 partId=1 localPresentationInstance=1 sourceId=2' from PresentationModuleStatusIndHandler[0x45e6a894]
2017-09-11T12:16:10.144+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:10.144+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to ResourceControllerImpl[0x45df5738]
2017-09-11T12:16:10.144+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to SystemStatusHandler_StandbyCtrl[0x45dfd860]
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to TokenBasedPresentationLocalView[0x45e6a578]
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to [0x45e6b00c]
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 APPL_ConfCtrl Snapshots: presentation = 1, local presentation = 0
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 MainEvents I: PresentationStarted() c=1 local p=1
2017-09-11T12:16:10.145+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to LayoutTransitionPresentationStartImpl[0x45ec979c]
2017-09-11T12:16:10.146+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...sync eventUpdate[0x467e189c] to AutoPresentationSelectionImpl[0x466c8c14]
2017-09-11T12:16:10.146+03:00 a8 appl[1754]: 9036.38 APPL_Addons AutoPresentationSelectionImpl::eventUpdate PresentationStarted
2017-09-11T12:16:10.146+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...async eventUpdate[0x467e189c] to PresentationServiceImpl[0x45e685f4]
2017-09-11T12:16:10.146+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...async eventUpdate[0x467e189c] to AutoPresentationStopImpl[0x45ec7cec]
2017-09-11T12:16:10.146+03:00 a8 appl[1754]: 9036.38 APPL_Addons AutoPresentationStopImpl::eventUpdate
2017-09-11T12:16:10.147+03:00 a8 appl[1754]: 9036.38 APPL_Addons AutoPresentationStopImpl::event_presentationStarted_local event presentation started source=2
2017-09-11T12:16:10.148+03:00 a8 appl[1754]: 9036.38 APPL_EventMonitor ...async eventUpdate[0x467e189c] to VideoInputClusterControlImpl[0x45dfc56c]
2017-09-11T12:16:10.164+03:00 a8 appl[1754]: 9036.40 APPL_ConfCtrl ConferenceController::async_tokenGranted (p=1,ch=-1,c=1)
2017-09-11T12:16:10.343+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor notifyEvent[0x466acea8] 'LocalLayoutFamilyChanged requested=auto selected=prominent' from LayoutFamilyUpdaterImpl[0x45dfe4c4]
2017-09-11T12:16:10.345+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor notifyEvent[0x45d73ec4] 'LocalLayoutToBeSet outputNo=1' from LayoutOutputSetterLocalStandard[0x4520dc3c]
2017-09-11T12:16:10.345+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor ...sync eventUpdate[0x45d73ec4] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:10.346+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor notifyEvent[0x466aa614] 'LayoutUpdated UPDATED_NORMAL partId=1 outputNo=1 outputGateId=110 channelNo=1 frames=[1 2 1172 2343 7656 7656 1 0][2 2 3864 0 2250 2250 2 0][1 10 0 0 10000 10000 0 0]
2017-09-11T12:16:10.346+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor ...sync eventUpdate[0x466aa614] to LocalAudioVideoInputAssocCtrlImpl[0x45e00944]
2017-09-11T12:16:10.348+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor ...sync eventUpdate[0x466aa614] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:10.348+03:00 a8 appl[1754]: 9036.58 MainEvents I: LayoutUpdated(p=1) outputNo=1 og=110
2017-09-11T12:16:10.348+03:00 a8 appl[1754]: 9036.58 MainEvents I: LayoutUpdated ...frame[1.1] presentation p=1 src=0 ig=106 placeholder=0 avatar=0 x=1172 y=2343 w=7656 h=7656 l=1 b=0 snapBorder stretch fcref=0
2017-09-11T12:16:10.349+03:00 a8 appl[1754]: 9036.58 MainEvents I: LayoutUpdated ...frame[1.2] main p=2 src=1 ig=118 placeholder=0 avatar=0 x=3864 y=0 w=2250 h=2250 l=2 b=0 stretch fcref=0
2017-09-11T12:16:10.349+03:00 a8 appl[1754]: 9036.58 MainEvents I: LayoutUpdated ...frame[GUI] gui p=1 src=1 ig=108 placeholder=0 avatar=0 x=0 y=0 w=10000 h=10000 l=0 b=0 letterbox fcref=0
2017-09-11T12:16:10.349+03:00 a8 appl[1754]: 9036.58 APPL_EventMonitor ...sync eventUpdate[0x466aa614] to AudioMuteCtrlImpl[0x45dfc184]
2017-09-11T12:16:10.361+03:00 a8 appl[1754]: 9036.59 APPL_EventMonitor notifyEvent[0x466acea8] 'LocalLayoutUpdateCompleted ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.362+03:00 a8 appl[1754]: 9036.59 APPL_EventMonitor ...sync eventUpdate[0x466acea8] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:10.362+03:00 a8 appl[1754]: 9036.60 APPL_EventMonitor ...sync eventUpdate[0x466acea8] to [0x45e6b00c]
2017-09-11T12:16:10.362+03:00 a8 appl[1754]: 9036.60 APPL_ConfCtrl Snapshots: presentation = 1, local presentation = 0
2017-09-11T12:16:10.363+03:00 a8 appl[1754]: 9036.60 APPL_EventMonitor ...sync eventUpdate[0x466acea8] to LayoutPredictorImpl[0x45ec95bc]
2017-09-11T12:16:10.389+03:00 a8 appl[1754]: 9036.62 APPL_EventMonitor notifyEvent[0x50241268] 'LayoutPredicitionUpdated 1002' from LayoutPredictorImpl[0x45ec95c4]
2017-09-11T12:16:10.403+03:00 a8 appl[1754]: 9036.64 ModesController I: ModesController::limitChannelRate [DuoModesController]: (cid:3) limit:[ Video(3):968000 ] active:[ Video(3):2000000 ] -> [ Video(3):968000 ]
2017-09-11T12:16:10.403+03:00 a8 appl[1754]: 9036.64 APPL_ConfCtrl RemoteParticipantImpl::outgoingDuoModeChanged (p=2,ch=3) ModesController wants to run mode: Video (3): H.264NIL 1920x1080@15.0 122400mbps 122400smbps 3133440maxnalsize 968k PT(116)
2017-09-11T12:16:10.408+03:00 a8 appl[1754]: 9036.64 APPL_EventMonitor notifyEvent[0x466e9c0c] 'LayoutUpdated UPDATED_NORMAL partId=2 outputNo=2 outputGateId=121 channelNo=3 frames=[1 2 0 0 10000 10000 1 0]
2017-09-11T12:16:10.408+03:00 a8 appl[1754]: 9036.64 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to LocalAudioVideoInputAssocCtrlImpl[0x45e00944]
2017-09-11T12:16:10.409+03:00 a8 appl[1754]: 9036.64 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:10.409+03:00 a8 appl[1754]: 9036.64 MainEvents I: LayoutUpdated(p=2) outputNo=2 og=121
2017-09-11T12:16:10.409+03:00 a8 appl[1754]: 9036.64 MainEvents I: LayoutUpdated ...frame[1.1] presentation p=1 src=0 ig=106 placeholder=0 avatar=0 x=0 y=0 w=10000 h=10000 l=1 b=0 stretch fcref=0
2017-09-11T12:16:10.409+03:00 a8 appl[1754]: 9036.64 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to AudioMuteCtrlImpl[0x45dfc184]
2017-09-11T12:16:10.409+03:00 a8 appl[1754]: 9036.64 APPL_Addons AudioMuteCtrlImpl::updateMuteOfLocalInputGatesInConference connect aig 102
2017-09-11T12:16:10.000+03:00 (none) vcodec: ENC_FSM-0: configure framerate 3000, bitrate 968000, video mtu 1296, packetization mode 1, maxnalusize 1382400, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0,dynamicmbps 108000, staticmbps 108000, fluxpacketlength 0, analyzerConfig '', EncodeForMotion
2017-09-11T12:16:10.000+03:00 (none) vcodec: adding layer: type: 0, prid: 56
2017-09-11T12:16:10.000+03:00 (none) vcodec: setting framerate: 30000 bitrate: 968000 for layer 0
2017-09-11T12:16:10.000+03:00 (none) vcodec: cq pair: h264_enc-0 <-> cq_wt-1
2017-09-11T12:16:10.000+03:00 (none) vcodec: IVA-HD debug trace (addr, size) = (0xb0032200, 5024)
2017-09-11T12:16:10.000+03:00 (none) vcodec: ENC_FSM-1: start standard H.264, width 1920, height: 1080, framerate 1500, bitrate 968000, video mtu 1296, packetization mode 1, maxnalusize 3133440, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 122400, static mbps 122400, analyzerConfig '', EncodeForSharpness
2017-09-11T12:16:10.000+03:00 (none) vcodec: adding layer: type: 0, prid: 56
2017-09-11T12:16:10.000+03:00 (none) vcodec: setting framerate: 15000 bitrate: 968000 for layer 0
2017-09-11T12:16:10.000+03:00 (none) vcodec: cq pair: h264_enc-1 <-> cq_wt-2
2017-09-11T12:16:10.000+03:00 (none) vcodec: IVA-HD debug trace (addr, size) = (0xb0038200, 5024)
2017-09-11T12:16:10.000+03:00 (none) vcodec: TTVEncCreateEncoder: created enc instance "ttvenc_h264_ivahd", w=1920 h=1080 br=968000 fr=15000 pkt_mode=1 host_ind=1
2017-09-11T12:16:10.000+03:00 (none) vcodec: ENC_FSM-1: configure framerate 1500, bitrate 968000, video mtu 1296, packetization mode 1, maxnalusize 3133440, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0,dynamicmbps 122400, staticmbps 122400, fluxpacketlength 12, analyzerConfig '', EncodeForSharpness
2017-09-11T12:16:10.431+03:00 a8 appl[1754]: 9036.66 APPL_EventMonitor ...async eventUpdate[0x466aa614] to MiscPipControlImpl[0x4520909c]
2017-09-11T12:16:10.432+03:00 a8 appl[1754]: 9036.66 APPL_Addons MiscPipControlImpl::eventUpdate
2017-09-11T12:16:10.432+03:00 a8 appl[1754]: 9036.66 APPL_EventMonitor ...async eventUpdate[0x466aa614] to SelfviewControlImpl[0x45e9e1e4]
2017-09-11T12:16:10.432+03:00 a8 appl[1754]: 9036.66 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to MiscPipControlImpl[0x4520909c]
2017-09-11T12:16:10.432+03:00 a8 appl[1754]: 9036.66 APPL_Addons MiscPipControlImpl::eventUpdate
2017-09-11T12:16:10.432+03:00 a8 appl[1754]: 9036.67 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to SelfviewControlImpl[0x45e9e1e4]
2017-09-11T12:16:10.567+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor notifyEvent[0x467e5274] 'RemoteOutputResolutionChanged partId=2 channelNumber=2' from MediaMonitorControl[0x45e67eb8]
2017-09-11T12:16:10.567+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor ...sync eventUpdate[0x467e5274] to SystemStatusHandler_Layout[0x45e997f0]
2017-09-11T12:16:10.567+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor notifyEvent[0x466f0170] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.569+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor notifyEvent[0x466d52c8] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.919+03:00 a8 appl: Last message '9036.80 APPL_EventMo' repeated 1 times, suppressed by syslog-ng on (none)
2017-09-11T12:16:10.570+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor notifyEvent[0x467e5274] 'VideoProtocolChanged partId=2 channelNumber=2' from MediaMonitorControl[0x45e67eb8]
2017-09-11T12:16:10.571+03:00 a8 appl[1754]: 9036.80 APPL_EventMonitor ...sync eventUpdate[0x467e5274] to AutoPresentationSelectionImpl[0x466c8c14]
2017-09-11T12:16:10.571+03:00 a8 appl[1754]: 9036.80 APPL_Addons AutoPresentationSelectionImpl::eventUpdate VideoProtocolChanged
2017-09-11T12:16:10.770+03:00 a8 appl[1754]: 9037.00 APPL_EventMonitor notifyEvent[0x466f0170] 'LocalLayoutUpdateCompleted ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:10.771+03:00 a8 appl[1754]: 9037.00 APPL_EventMonitor ...sync eventUpdate[0x466f0170] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:10.771+03:00 a8 appl[1754]: 9037.00 APPL_EventMonitor ...sync eventUpdate[0x466f0170] to [0x45e6b00c]
2017-09-11T12:16:10.771+03:00 a8 appl[1754]: 9037.00 APPL_ConfCtrl Snapshots: presentation = 1, local presentation = 0
2017-09-11T12:16:10.771+03:00 a8 appl[1754]: 9037.00 APPL_EventMonitor ...sync eventUpdate[0x466f0170] to LayoutPredictorImpl[0x45ec95bc]
2017-09-11T12:16:10.777+03:00 a8 appl[1754]: 9037.01 APPL_EventMonitor notifyEvent[0x50243ee0] 'LayoutPredicitionUpdated 1002' from LayoutPredictorImpl[0x45ec95c4]
2017-09-11T12:16:11.843+03:00 a8 appl[1754]: 9038.08 SipPacket SIP Msg: Outgoing => REGISTER, CSeq: 18125 REGISTER, Remote: 10.30.16.5:5061, CallId: 24a8db35744b1a5a5ca8e32e00bb88af
2017-09-11T12:16:11.843+03:00 a8 appl[1754]: 9038.08 SipPacket REGISTER sip:vtcSIP/2.0
2017-09-11T12:16:11.843+03:00 a8 appl[1754]: 9038.08 SipPacket Via: SIP/2.0/TLS 172.16.15.15:5061;branch=z9hG4bK662426a2777a0a9aa9fc0ac547a20a04;rport
2017-09-11T12:16:11.843+03:00 a8 appl[1754]: 9038.08 SipPacket Call-ID: 24a8db35744b1a5a5ca8e32e00bb88af
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket CSeq: 18125 REGISTER
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Contact: <sip:sx20@172.16.15.15:5061;transport=tls>;+sip.instance="<urn:uuid:0dde6ee8-3456-52ca-b0f8-44f4cd3dac35>";sip.cisco.multistream
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket From: <sip:sx20@vtc>;tag=11f284d96225e2ca
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket To: <sip:sx20
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Max-Forwards: 70
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Route: <sip:10.30.16.5;lr>
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Allow: INVITE,ACK,CANCEL,BYE,UPDATE,INFO,OPTIONS,REFER,NOTIFY
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket User-Agent: TANDBERG/529 (ce8.2.2.3263c59) Cisco-SX20
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Expires: 3600
2017-09-11T12:16:11.844+03:00 a8 appl[1754]: 9038.08 SipPacket Supported: replaces,100rel,timer,gruu,path,outbound
2017-09-11T12:16:11.845+03:00 a8 appl[1754]: 9038.08 SipPacket Content-Length: 0
2017-09-11T12:16:11.845+03:00 a8 appl[1754]: 9038.08 SipPacket
2017-09-11T12:16:11.850+03:00 a8 appl[1754]: 9038.08 SipPacket SIP Msg: Incoming <= 200 OK, CSeq: 18125 REGISTER, Remote: 10.30.16.5:5061, CallId: 24a8db35744b1a5a5ca8e32e00bb88af
2017-09-11T12:16:11.851+03:00 a8 appl[1754]: 9038.08 SipPacket SIP/2.0 200 OK
2017-09-11T12:16:11.852+03:00 a8 appl[1754]: 9038.08 SipPacket Via: SIP/2.0/TLS 172.16.15.15:5061;branch=z9hG4bK662426a2777a0a9aa9fc0ac547a20a04;received=172.16.15.15;rport=52475
2017-09-11T12:16:11.852+03:00 a8 appl[1754]: 9038.09 SipPacket Call-ID: 24a8db35744b1a5a5ca8e32e00bb88af
2017-09-11T12:16:11.852+03:00 a8 appl[1754]: 9038.09 SipPacket CSeq: 18125 REGISTER
2017-09-11T12:16:11.852+03:00 a8 appl[1754]: 9038.09 SipPacket Contact: <sip:sx20@172.16.15.15:5061;transport=tls>;+sip.instance="<urn:uuid:0dde6ee8-3456-52ca-b0f8-44f4cd3dac35>";pub-gruu="sip:sx20@;gr=urn:uuid:0dde6ee8-3456-52ca-b0f8-44f4cd3dac35";expires=60
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket From: <sip:sx20@vtc>;tag=11f284d96225e2ca
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket To: <sip:sx20@vtc>;tag=ab4924f91d32e054
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket Server: TANDBERG/4134 (X8.9)
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket Date: Sun, 11 Sep 2017 09:16:11 GMT
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket Supported: com.tandberg.sdp.extensions.v1,gruu,outbound,path
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket Content-Length: 0
2017-09-11T12:16:11.853+03:00 a8 appl[1754]: 9038.09 SipPacket
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp onHelloTimer
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp addTransaction (num transactions=1)
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp Transaction: Id 19 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp execTransaction
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp Transaction: Id 19 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:17.820+03:00 a8 appl[1754]: 9044.05 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp BFCP packet arrived (len=56)
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp BFCP session received data. Decoding ...
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp handleHelloAck
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp handleHelloAck: ack in session 2 after 0 attempts
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp deleteTransaction
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp removeTransaction
2017-09-11T12:16:17.825+03:00 a8 appl[1754]: 9044.06 Bfcp Transaction: Id 19 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:27.826+03:00 a8 appl[1754]: 9054.06 Bfcp onHelloTimer
2017-09-11T12:16:27.826+03:00 a8 appl[1754]: 9054.06 Bfcp addTransaction (num transactions=1)
2017-09-11T12:16:27.826+03:00 a8 appl[1754]: 9054.06 Bfcp Transaction: Id 20 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:27.826+03:00 a8 appl[1754]: 9054.06 Bfcp execTransaction
2017-09-11T12:16:27.826+03:00 a8 appl[1754]: 9054.06 Bfcp Transaction: Id 20 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:27.827+03:00 a8 appl[1754]: 9054.06 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:27.845+03:00 a8 appl[1754]: 9054.08 Bfcp BFCP packet arrived (len=56)
2017-09-11T12:16:27.845+03:00 a8 appl[1754]: 9054.08 Bfcp BFCP session received data. Decoding ...
2017-09-11T12:16:27.846+03:00 a8 appl[1754]: 9054.08 Bfcp handleHelloAck
2017-09-11T12:16:27.847+03:00 a8 appl[1754]: 9054.08 Bfcp handleHelloAck: ack in session 2 after 0 attempts
2017-09-11T12:16:27.847+03:00 a8 appl[1754]: 9054.08 Bfcp deleteTransaction
2017-09-11T12:16:27.847+03:00 a8 appl[1754]: 9054.08 Bfcp removeTransaction
2017-09-11T12:16:27.847+03:00 a8 appl[1754]: 9054.08 Bfcp Transaction: Id 20 Sess 2 Floor 2 Role Client Prim BFCP_HELLO
2017-09-11T12:16:28.222+03:00 a8 appl[1754]: 9054.45 CuilApp User __remotetouchdevice__ about to execute command '/Presentation/Stop' from ::ffff:172.16.15.16.
2017-09-11T12:16:28.223+03:00 a8 appl[1754]: 9054.46 CuilApp User __remotetouchdevice__ successfully executed command '/Presentation/Stop' from ::ffff:172.16.15.16.
2017-09-11T12:16:28.224+03:00 a8 appl[1754]: 9054.46 APPL_ConfCtrl PresentationServiceImpl::stopPresentingAll
2017-09-11T12:16:28.224+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor notifyEvent[0x467e9da8] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.224+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor notifyEvent[0x466adba0] 'LocalLayoutFamilyChanged requested=auto selected=overlay' from LayoutFamilyUpdaterImpl[0x45dfe4c4]
2017-09-11T12:16:28.225+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor notifyEvent[0x467cefac] 'LocalLayoutToBeSet outputNo=1' from LayoutOutputSetterLocalStandard[0x4520dc3c]
2017-09-11T12:16:28.226+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor ...sync eventUpdate[0x467cefac] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:28.226+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor notifyEvent[0x467e8f64] 'LayoutUpdated UPDATED_NORMAL partId=1 outputNo=1 outputGateId=110 channelNo=1 frames=[2 2 0 0 10000 10000 1 0][1 10 0 0 10000 10000 0 0]
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor ...sync eventUpdate[0x467e8f64] to LocalAudioVideoInputAssocCtrlImpl[0x45e00944]
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor ...sync eventUpdate[0x467e8f64] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 MainEvents I: LayoutUpdated(p=1) outputNo=1 og=110
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 MainEvents I: LayoutUpdated ...frame[1.1] speaker p=2 src=1 ig=118 placeholder=0 avatar=0 x=0 y=0 w=10000 h=10000 l=1 b=0 stretch fcref=0
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 MainEvents I: LayoutUpdated ...frame[GUI] gui p=1 src=1 ig=108 placeholder=0 avatar=0 x=0 y=0 w=10000 h=10000 l=0 b=0 letterbox fcref=0
2017-09-11T12:16:28.227+03:00 a8 appl[1754]: 9054.46 APPL_EventMonitor ...sync eventUpdate[0x467e8f64] to AudioMuteCtrlImpl[0x45dfc184]
2017-09-11T12:16:28.235+03:00 a8 appl[1754]: 9054.47 APPL_EventMonitor notifyEvent[0x466adba0] 'LocalLayoutUpdateCompleted ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.237+03:00 a8 appl[1754]: 9054.47 APPL_EventMonitor ...sync eventUpdate[0x466adba0] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:28.237+03:00 a8 appl[1754]: 9054.47 APPL_EventMonitor ...sync eventUpdate[0x466adba0] to [0x45e6b00c]
2017-09-11T12:16:28.238+03:00 a8 appl[1754]: 9054.47 APPL_ConfCtrl Snapshots: presentation = 0, local presentation = 0
2017-09-11T12:16:28.238+03:00 a8 appl[1754]: 9054.47 APPL_EventMonitor ...sync eventUpdate[0x466adba0] to LayoutPredictorImpl[0x45ec95bc]
2017-09-11T12:16:28.248+03:00 a8 appl[1754]: 9054.48 APPL_EventMonitor notifyEvent[0x467df470] 'LayoutPredicitionUpdated 1002' from LayoutPredictorImpl[0x45ec95c4]
2017-09-11T12:16:28.249+03:00 a8 appl[1754]: 9054.48 APPL_EventMonitor notifyEvent[0x466a9488] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.249+03:00 a8 appl[1754]: 9054.48 APPL_EventMonitor notifyEvent[0x466bc440] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.262+03:00 a8 appl[1754]: 9054.50 APPL_ConfCtrl RemoteParticipantImpl::outgoingDuoModeChanged (p=2,ch=3) ModesController wants to run mode: Video (3): vid-off 0x0@0.0 0k
2017-09-11T12:16:28.266+03:00 a8 appl[1754]: 9054.50 APPL_EventMonitor notifyEvent[0x45dfcaec] 'LayoutUpdated UPDATED_NORMAL partId=2 outputNo=2 outputGateId=121 channelNo=3 frames=' from LayoutOutputSetterRemote[0x50213354]
2017-09-11T12:16:28.267+03:00 a8 appl[1754]: 9054.50 APPL_EventMonitor ...sync eventUpdate[0x45dfcaec] to LocalAudioVideoInputAssocCtrlImpl[0x45e00944]
2017-09-11T12:16:28.270+03:00 a8 appl[1754]: 9054.50 APPL_EventMonitor ...sync eventUpdate[0x45dfcaec] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:28.270+03:00 a8 appl[1754]: 9054.50 MainEvents I: LayoutUpdated(p=2) outputNo=2 og=121
2017-09-11T12:16:28.271+03:00 a8 appl[1754]: 9054.50 APPL_EventMonitor ...sync eventUpdate[0x45dfcaec] to AudioMuteCtrlImpl[0x45dfc184]
2017-09-11T12:16:28.271+03:00 a8 appl[1754]: 9054.50 APPL_Addons AudioMuteCtrlImpl::updateMuteOfLocalInputGatesInConference disconnect aig 102
2017-09-11T12:16:28.282+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor notifyEvent[0x466e9c0c] 'PresentationStopped cause_new=0 confId=1 local=1 partId=1 localPresentationInstance=1 cause=normal' from PresentationModuleStatusIndHandler[0x45e6a894]
2017-09-11T12:16:28.283+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:28.283+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to ResourceControllerImpl[0x45df5738]
2017-09-11T12:16:28.283+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to SystemStatusHandler_StandbyCtrl[0x45dfd860]
2017-09-11T12:16:28.283+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to [0x45e6b00c]
2017-09-11T12:16:28.284+03:00 a8 appl[1754]: 9054.52 APPL_ConfCtrl Snapshots: presentation = 0, local presentation = 0
2017-09-11T12:16:28.284+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:28.285+03:00 a8 appl[1754]: 9054.52 MainEvents I: PresentationStopped() c=1 local p=1 cause=[normal]
2017-09-11T12:16:28.285+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...sync eventUpdate[0x466e9c0c] to LayoutTransitionPresentationStartImpl[0x45ec979c]
2017-09-11T12:16:28.286+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor notifyEvent[0x50225cf0] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.286+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor notifyEvent[0x466d06c0] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.684+03:00 a8 appl: Last message '9054.52 APPL_EventMo' repeated 1 times, suppressed by syslog-ng on (none)
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 APPL_ConfCtrl ConferenceSessionImpl::localTokenReleaseReq (c=1)
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 APPL_ConfCtrl RemoteParticipantImpl::sendTokenMsg (p=2)ReleaseReq tokenId=2 tokenReqId=14 tokenHolderId=0
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp doBFCPFloorReleaseReq
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp doFloorReleaseReq
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp addTransaction (num transactions=1)
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_RELEASE
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp execTransaction
2017-09-11T12:16:28.287+03:00 a8 appl[1754]: 9054.52 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_RELEASE
2017-09-11T12:16:28.288+03:00 a8 appl[1754]: 9054.52 Bfcp startUdpRetransmitTimer Starting UDP timer for session 2
2017-09-11T12:16:28.288+03:00 a8 appl[1754]: 9054.52 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:28.288+03:00 a8 appl[1754]: 9054.52 APPL_ConfCtrl ConferenceSessionImpl::tokenOwnerChanged (c=1, newp=-1, oldp=1)(token=2)
2017-09-11T12:16:28.290+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x467e8f64] to MiscPipControlImpl[0x4520909c]
2017-09-11T12:16:28.290+03:00 a8 appl[1754]: 9054.52 APPL_Addons MiscPipControlImpl::eventUpdate
2017-09-11T12:16:28.290+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x467e8f64] to SelfviewControlImpl[0x45e9e1e4]
2017-09-11T12:16:28.290+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x45dfcaec] to MiscPipControlImpl[0x4520909c]
2017-09-11T12:16:28.290+03:00 a8 appl[1754]: 9054.52 APPL_Addons MiscPipControlImpl::eventUpdate
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x45dfcaec] to SelfviewControlImpl[0x45e9e1e4]
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to PresentationServiceImpl[0x45e685f4]
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to AutoPresentationStopImpl[0x45ec7cec]
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_Addons AutoPresentationStopImpl::eventUpdate
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_Addons AutoPresentationStopImpl::event_presentationStopped_local
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to VideoInputClusterControlImpl[0x45dfc56c]
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_EventMonitor ...async eventUpdate[0x466e9c0c] to AutoPresentationSelectionImpl[0x466c8c14]
2017-09-11T12:16:28.291+03:00 a8 appl[1754]: 9054.52 APPL_Addons AutoPresentationSelectionImpl::eventUpdate PresentationStopped
2017-09-11T12:16:28.294+03:00 a8 appl[1754]: 9054.53 Bfcp BFCP packet arrived (len=28)
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp BFCP session received data. Decoding ...
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp handleFloorRequestStatus
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp handleFloorRequestStatus: Incoming FLOOR_REQUEST_STATUS=BFCP_RELEASED, request_id=14
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp addTransaction (num transactions=2)
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_RELEASED
2017-09-11T12:16:28.295+03:00 a8 appl[1754]: 9054.53 Bfcp execTransaction
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_RELEASE
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_RELEASED
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp execTransaction BFCP_FLOOR_RELEASE already in progress
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp deleteTransaction
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp removeTransaction
2017-09-11T12:16:28.296+03:00 a8 appl[1754]: 9054.53 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_RELEASE
2017-09-11T12:16:28.382+03:00 a8 appl[1754]: 9054.61 APPL_EventMonitor notifyEvent[0x466f0e84] 'RemoteOutputResolutionChanged partId=2 channelNumber=2' from MediaMonitorControl[0x45e67eb8]
2017-09-11T12:16:28.382+03:00 a8 appl[1754]: 9054.61 APPL_EventMonitor ...sync eventUpdate[0x466f0e84] to SystemStatusHandler_Layout[0x45e997f0]
2017-09-11T12:16:28.382+03:00 a8 appl[1754]: 9054.62 APPL_EventMonitor notifyEvent[0x466c27a0] 'LocalLayoutUpdateRequested ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.721+03:00 a8 appl: Last message '9054.62 APPL_EventMo' repeated 2 times, suppressed by syslog-ng on (none)
2017-09-11T12:16:28.383+03:00 a8 appl[1754]: 9054.62 APPL_EventMonitor notifyEvent[0x466f0e84] 'VideoProtocolChanged partId=2 channelNumber=2' from MediaMonitorControl[0x45e67eb8]
2017-09-11T12:16:28.383+03:00 a8 appl[1754]: 9054.62 APPL_EventMonitor ...sync eventUpdate[0x466f0e84] to AutoPresentationSelectionImpl[0x466c8c14]
2017-09-11T12:16:28.383+03:00 a8 appl[1754]: 9054.62 APPL_Addons AutoPresentationSelectionImpl::eventUpdate VideoProtocolChanged
2017-09-11T12:16:28.450+03:00 a8 appl[1754]: 9054.68 APPL_ConfCtrl ConferenceController::async_tokenReleased (c=1)
2017-09-11T12:16:28.450+03:00 a8 appl[1754]: 9054.68 APPL_EventMonitor notifyEvent[0x466eeff4] 'PresentationTokenReleased confId=1' from PresentationServiceImpl[0x45e685fc]
2017-09-11T12:16:28.450+03:00 a8 appl[1754]: 9054.68 APPL_EventMonitor ...sync eventUpdate[0x466eeff4] to ResourceControllerImpl[0x45df5738]
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 APPL_EventMonitor ...sync eventUpdate[0x466eeff4] to MainEventLogProducerImpl[0x45ea493c]
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 MainEvents I: PresentationTokenReleased() c=1
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 APPL_EventMonitor ...sync eventUpdate[0x466eeff4] to LayoutTransitionPresentationStartImpl[0x45ec979c]
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 Bfcp execTransaction
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_RELEASED
2017-09-11T12:16:28.451+03:00 a8 appl[1754]: 9054.68 Bfcp BFCP packet to send. Encoding ...
2017-09-11T12:16:28.452+03:00 a8 appl[1754]: 9054.68 Bfcp deleteTransaction
2017-09-11T12:16:28.452+03:00 a8 appl[1754]: 9054.68 Bfcp removeTransaction
2017-09-11T12:16:28.452+03:00 a8 appl[1754]: 9054.68 Bfcp Transaction: Id 21 Sess 2 Floor 2 Role Client Prim BFCP_FLOOR_REQUEST_STATUS Stat BFCP_RELEASED
2017-09-11T12:16:28.488+03:00 a8 appl[1754]: 9054.72 APPL_EventMonitor notifyEvent[0x466d52c8] 'LocalLayoutUpdateCompleted ' from LayoutUpdaterImpl[0x45dfe644]
2017-09-11T12:16:28.489+03:00 a8 appl[1754]: 9054.72 APPL_EventMonitor ...sync eventUpdate[0x466d52c8] to ExternalEventProducerImpl[0x45df0470]
2017-09-11T12:16:28.489+03:00 a8 appl[1754]: 9054.72 APPL_EventMonitor ...sync eventUpdate[0x466d52c8] to [0x45e6b00c]
2017-09-11T12:16:28.490+03:00 a8 appl[1754]: 9054.72 APPL_ConfCtrl Snapshots: presentation = 0, local presentation = 0
2017-09-11T12:16:28.490+03:00 a8 appl[1754]: 9054.72 APPL_EventMonitor ...sync eventUpdate[0x466d52c8] to LayoutPredictorImpl[0x45ec95bc]
2017-09-11T12:16:28.495+03:00 a8 appl[1754]: 9054.73 APPL_EventMonitor notifyEvent[0x5023af78] 'LayoutPredicitionUpdated 1002' from LayoutPredictorImpl[0x45ec95c4]
2017-09-11T12:16:28.000+03:00 (none) vcodec: ENC_FSM-0: configure framerate 6000, bitrate 1744000, video mtu 1296, packetization mode 1, maxnalusize 1382400, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0,dynamicmbps 216000, staticmbps 216000, fluxpacketlength 0, analyzerConfig '', EncodeForMotion
2017-09-11T12:16:28.000+03:00 (none) vcodec: adding layer: type: 0, prid: 56
2017-09-11T12:16:28.000+03:00 (none) vcodec: setting framerate: 60000 bitrate: 1744000 for layer 0
2017-09-11T12:16:28.000+03:00 (none) vcodec: cq pair: h264_enc-0 <-> cq_wt-1
2017-09-11T12:16:28.000+03:00 (none) vcodec: IVA-HD debug trace (addr, size) = (0xb0032200, 5024)
2017-09-11T12:16:28.000+03:00 (none) vcodec: ENC_FSM-1: configure framerate 1500, bitrate 968000, video mtu 1296, packetization mode 1, maxnalusize 3133440, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0,dynamicmbps 122400, staticmbps 122400, fluxpacketlength 0, analyzerConfig '', EncodeForSharpness
2017-09-11T12:16:28.000+03:00 (none) vcodec: TTVEncDestroyEncoder: destroyed enc instance "ttvenc_h264_ivahd"
2017-09-11T12:16:35.845+03:00 a8 appl[1754]: 9062.08 CuilApp User admin about to execute command '/Logging/ExtendedLogging/Stop'

 

Updated my SXS20s to the latest soft. CE 9.1.4. 

This had no effect unfortunately, same problem still. 

Zoltan Kelemen
Cisco Employee

Hi Mikhel,

 

what is the call flow on a TMS scheduled conference?

Is it dial out to endpoints (automatic connect most often) or dial in to bridge (OBTP or manual connect)
Specifically, call direction should be specified in participant details as well.

 

How does this call flow differ from "direct" connection?
Is a H323 gateway running on CMS?

 

Software version is unlikely to be related to this. Also, BFCP is a SIP only thing, with H323 it works differently, I'm not sure about webRTC clients, but I think it may be different as well.


While TMS manages calls in a way, it will never actually be in the call path, but rather instruct various infrastructure elements to initiate the calls.

Hei!

 

Thnaks for answering so quick. 

I hope I understood you correctly: 

 

TMS flow is dial out to endpoints (cms dials to endpoints) 

 

Under direct I mean users choose a cospace from the device phone book and place the call to that cospace.

 

The H323 gateway is running but this is not relevant anymore i think since, even if i have only sx20 endpoint in the conference, no presentation is showed anyway. 

 

 

What are the involved call control element(s)?
CUCM? VCS?

you will need to identify how each call (inbound vs outbound) is routed:

- do they go through the same devices or not?

- what search rules, zones, trunks etc does the call pass through in each case?

 

If CUCM is involved, ensure that the SIP Profile of all trunks / endpoints etc involved in the calls supports BFCP.

I use expressway for call control. 

However as I understand it, expway is utilized only when devices themselves call out. 

 

So as I understand it goes like this: 

Booking made in TMS-->API command to CMS-->CMS is ordered to make a SIP call to devices.


I am fairly certain the problem lies somewhere in the TMS settings going to CMS for the call. 
Any scenario where TMS is not involved, there are no problems (point to point calls, calling to spaces, s4b clients dialing straight to endpoints via cms etc). 

 

 

Create
Recognize Your Peers
Content for Community-Ad