SBGZA.JHB.5SIMM.436 /var/log/messages Date:2013-10-01 12:25:45
Snowball 3.1.2 Release build #35267
G3/HDX 7000 HD - build date: 2013-07-26 10:06:17-0500 by: buildmaster
2013-10-01 09:26:57 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1907 vout2 1920 1080 DVID 50 interlaced vinp 0 0 1920 1080 0 none
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: TM Common STATUS: Shared IRAM: record: 7 base: 0x11819000, size: 104
2013-10-01 09:26:57 DEBUG jvm: pc[0]: UI: asyn: DisplayManager: updateVCRVideoPanel
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: TM Common STATUS: Shared IRAM: record: 11 base: 0x11819068, size: 22528
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: TM Common STATUS: Shared IRAM: record: 16 base: 0x1181e868, size: 8088
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.8 record, it is for internal memory w/ attr 1 algorithm 3
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.10 record, allocated 4096 bytes, base is 8bb28400 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.11 record, it is for internal memory w/ attr 0 algorithm 3
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.12 record, allocated 5120 bytes, base is 8bb29480 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.13 record, allocated 23552 bytes, base is 8bb2a900 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.14 record, allocated 5120 bytes, base is 8bb30580 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.15 record, allocated 3623424 bytes, base is 8bb31a00 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.16 record, it is for internal memory w/ attr 0 algorithm 3
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.17 record, allocated 97664 bytes, base is 8bea6480 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.18 record, allocated 1408 bytes, base is 8bebe280 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: For No.19 record, allocated 128 bytes, base is 8bebe880 space 9
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: ResMgrMemStart_Enc: memInit returned pCodecHandle 8ba0d780 pCodecFxns 8ba0d880
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: Inside memStart_MSCE_server() ResMgrMemStart_Enc returned pCodecResMgrHandle 0x8ba0d780 pCodecFxns 0x8ba0d880
2013-10-01 09:26:57 DEBUG avc: hd[6]: Done ResMgrMemStart_Enc_MSC() codecImpleID 1 pChanHandle 0x0x374658 *ppCodecResMgrHandle 0x0x8ba0d780
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetState Opening has split info 1/1 profile HighProfile-HighProfile
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: GetReconstructedFrame: 0x8fb1618a
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: SendReconstructedFrame: 0x8fb16188
2013-10-01 09:26:57 DEBUG avc: hd[6]: GET_STATISTICS_MEMORY_POOL_CB gets phy 0x0x861b7000 virt 0x0x4001d000
2013-10-01 09:26:57 DEBUG avc: pc[0]: PeopEncHighRes2[1].H26xEncoder[106]: opened
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0].PeopEncHighRes2[1]: opened
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0].PeopEncHighRes2[1]: Done SetState to new state 2 w/ err 0
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0].PeopEncHighRes2[1]: Done SetState to new state 1 w/ err 0
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0].PeopEncHighRes2[1]: SetEnable to ENABLE w/ chainHeight 1 chainWidth 8
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0].PeopEncHighRes2[1]: Done SetEnable() to ENABLE
2013-10-01 09:26:57 DEBUG avc: pc[0]: NOTIFY: VID videoroute set 1866 complete pe01 0 0 Unknown 0 Progressive
2013-10-01 09:26:57 DEBUG avc: pc[0]: RouteProc[0]: RouteProcNewRoute: Found 525 mon3 - 1865
2013-10-01 09:26:57 DEBUG avc: pc[0]: RouteProc[0]: RouteProcNewRoute: Updating mon3 - 1865
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0]: ChainCtrlUpdateRoute: Updating Node kVideoRouteNodeMonitor
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: Current Route mon3: Dest WxH 1280 x 720, Ref 25, Inter
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: Current Src Count 1
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: Current src nosignal, w 1280, h 720 x 0, y 0, z 0 ar none, cx 0, cy 0, fr 0
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlUpdateMonitorRoute new route Route mon3: Dest WxH 704 x 576, Ref 25, Inter
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlUpdateMonitorRoute new route Src Count 1
2013-10-01 09:26:57 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlUpdateMonitorRoute new route src vout1, w 704, h 576 x 0, y 0, z 0 ar none, cx 0, cy 0, fr 0
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0]: ChainCtrlUpdateMonitorRoute: sourcecount 1
2013-10-01 09:26:57 DEBUG avc: pc[0]: ChainCtrl[0]: FindVideoChain: Looking for vout1, role unknown, spec unknown
2013-10-01 09:26:57 DEBUG avc: pc[0]: VideoDevStartVCRClock calling VIOP_ioctl mon 1, timing 56
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: PLCMVE_GetStatisticsMemoryPool_cb_client got back 0x861b7000
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: VE_Initialize done for Opening
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: Profile change has VE_SetMaxPacketSize to be 0
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: MapCodecAlgorithm returning ALG_MODE_H264_HP for alg h264 profile 100
2013-10-01 09:26:57 INFO avc: hd[6]: DSP: TXNVE_SetAlgorithm: 6
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: VE_SetAlgorithm h264 profile HighProfile
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetFrameAttenuationState: debugMode 0x0
2013-10-01 09:26:57 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: VE_SetStreamMode w/ value 0
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: VE_SignalContent w/ value 0
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: maxCodingRes 1280:720 H.241 level ID 85 for TI 3 h264
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: opened
2013-10-01 09:26:58 INFO avc: hd[6]: H26xEncoder[106]: Calling SetState() oldState 2 => newState 2 w/ alg 3 h264 profile HighProfile
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: codecImplID 1 for 3 h264
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: done for changing state to 2
2013-10-01 09:26:58 DEBUG avc: hd[6]: H26xEncoder[106]: SetState: done for changing state to 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: VideoDevReConfigureMonitor calling VIOP_setVidMode mon 1, signal 4, timing 56
2013-10-01 09:26:58 DEBUG avc: pc[0]: ikeVideoCrossBarSetMonitorMode Success (monitor ID 1 timingmode 56)
2013-10-01 09:26:58 DEBUG avc: pc[0]: ikeVideoCrossBarSetVcrRoute Success (vcr ID 3 dsp 2 recorded monitor 1)
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: VID videoroute set 1887 complete mon3 704 576 SVideo 25 Interlaced
2013-10-01 09:26:58 DEBUG avc: pc[0]: RouteProc[0]: RouteProcNewRoute: Found 525 vout1 - 1868
2013-10-01 09:26:58 DEBUG avc: pc[0]: RouteProc[0]: RouteProcNewRoute: Updating vout1 - 1868
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: ChainCtrlUpdateRoute: Updating Node kVideoRouteNodeVideoOut
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: Current Route vout1: Dest WxH 1920 x 1080, Ref 50, Prog
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: Current Src Count 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: Current src black, w 1920, h 1080 x 0, y 0, z 0 ar none, cx 0, cy 0, fr 0
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlHandleVoutChainIke new route Route vout1: Dest WxH 1920 x 1080, Ref 50, Prog
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlHandleVoutChainIke new route Src Count 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: VIDEO[0]: ChainCtrlHandleVoutChainIke new route src vinp, w 1920, h 1080 x 0, y 0, z 0 ar none, cx 0, cy 0, fr 0
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: FindVideoChain: Looking for vout1, role unknown, spec unknown
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: ChainCtrlHandleVirtualVoutChain on VideoOut1Ike[0]
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: FindVideoChain: Looking for vinp, role unknown, spec unknown
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].VideoOut1Ike[0]: VideoChainIncConnCount: connCount row 0, 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: Dumping Attrib: GetBestSource: match
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: ^Isrc, not fixed, width 0, height 0
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: ^IkAspectNotDefined, none, section 0 of 0
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: ^Inot filtered, people, kYUV420Planar, kVideoLocFar
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: ^IkVideoSpecHiRes, framerate 0, bypass off, no filter
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: VideoChainIncConnCount: connCount row 0, 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompositorChan[4]: VideoInChainBuildRoute: Got Channel at 0x4
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompositorChan[4]: ChanAddOutputProxyClient: allocating output on proc 3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompositorChan[4]: adding VideoChannel[7] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompSrcChan[3]: VideoInChainBuildRoute: Got Channel at 0x3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompSrcChan[3]: deleting CompositorChan[4] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompSrcChan[3]: ChanAddOutputProxyClient: allocating output on proc 3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].CompSrcChan[3]: adding CompositorChan[4] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].FormatConverter[2]: VideoInChainBuildRoute: Got Channel at 0x2
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].FormatConverter[2]: deleting CompSrcChan[3] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].FormatConverter[2]: ChanAddOutputProxyClient: allocating output on proc 3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].FormatConverter[2]: adding CompSrcChan[3] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoInChannel[1]: VideoInChainBuildRoute: Got Channel at 0x1
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoInChannel[1]: deleting FormatConverter[2] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoInChannel[1]: ChanAddOutputProxyClient: allocating output on proc 3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoInChannel[1]: adding FormatConverter[2] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VidPWChan[0]: adding VideoInChannel[1] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoChannel[7]: opened
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: VideoInChainGetChannel: getting back VideoChannel[7]
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].PeopleVInIke[0]: ===== The connection IOChan row is 0
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: Looking for subchain for vinp
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: ChainCtrlGetUnUsedVoutSubChain: looking for VoutSubChain2
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0]: hooking PeopleVInIke[0] => VideoOut1Ike[0] Count 1 WxH 1920x1080
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].VoutSubChain2[2]: VideoChainIncConnCount: connCount row 0, 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].VidHWAccChan[207]: opened
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].VideoOutChan[206]: closed
2013-10-01 09:26:58 DEBUG avc: pc[0]: ikeVideoCrossBarSetDspInput: disable dsp 1 Input
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompositorChan[4]: adding VideoChannel[7] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompositorChan[4]: CompCanvasAddSource: Already in the list
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompSrc[3]: deleting CompositorChan[4] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompSrc[3]: adding CompositorChan[4] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: FormatConverter[2]: deleting CompSrc[3] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: FormatConverter[2]: adding CompSrc[3] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoInChannel[1]: deleting FormatConverter[2] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoInChannel[1]: adding FormatConverter[2] as an output
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoChannel[7]: opened
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompositorChan[4]: Setting comp func to 3
2013-10-01 09:26:58 DEBUG avc: ti[3]: CompositorChan[4]: Set pass through mode to 1
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutChan[206]: closed
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].VideoOutChan[206]: opened
2013-10-01 09:26:58 DEBUG avc: pc[0]: ikeVideoCrossBarSetDspInput: enable dsp 1 Input
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].ImageProc[201]: opened
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutChan[206]: VideoOutSetState: vidout_open port 1, width 1920, height 1080, refresh 50, prog 3, pri 1
2013-10-01 09:26:58 DEBUG avc: ti[3]: vidout_customize: port 1, width 1920, height 1080, framerate 25, progressive 3, edmaPri 1 maxBufs 4
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutCustomize calling FVID_create with cfgStr /VP1DISPLAY
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutChan[206]: SetState: Opening Display
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutChan[206]: opened
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoOutSetDisplay: Setting display with state 1
2013-10-01 09:26:58 DEBUG avc: ti[3]: ImageProc[201]: opened
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].VideoChannel[200]: opened
2013-10-01 09:26:58 DEBUG avc: pc[0]: ChainCtrl[0].VoutSubChain2[2]: VideoChainIncConnCount: connCount row 0, 2
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoChannel[7]: ChanAddOutputProxyClient: allocating output on proc 3
2013-10-01 09:26:58 DEBUG avc: pc[0]: PeopleVInIke[0].VideoChannel[7]: adding VideoChannel[200] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: VideoDev[1]: found 56 timing mode
2013-10-01 09:26:58 DEBUG avc: pc[0]: ikeVideoCrossBarSetDspToGennumRoute Success (monitor ID 1 dsp 1)
2013-10-01 09:26:58 DEBUG avc: pc[0]: VoutSubChain2[2].VidHWAccChan[207]: adding VidHWAccChan[0] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: Setting payload 100 on UnknownTx
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) rate set to 5Kbps
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) payload:100, packetLen:0 sampleRate:1
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: Open Tx channel of type fecc encrypt=none
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: ConnAddTxChan: updating connId on TxFecc with 39
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM connection commChanAdded 39 45 feccTx "" ""
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: tx chan RTPTxChan[9] added
2013-10-01 09:26:58 DEBUG avc: pc[0]: FeccTxChan[0]: adding RTPTxChan[9] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel state 45 opening none H.224 39
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) Setting src address to 10.5.148.240
2013-10-01 09:26:58 DEBUG avc: pc[0]: sockOpen socket 102 to address 10.5.148.240:49212
2013-10-01 09:26:58 DEBUG avc: pc[0]: sockOpen socket 107 to address 10.5.148.240:49213
2013-10-01 09:26:58 DEBUG avc: pc[0]: h323nat: localIP = "10.5.148.240", farIP = "10.144.137.203", both are non-routable but on different LANs.
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: (FECCTx) Opening "fecc" channel
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoChannel[200]: opened
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoChannel[7]: adding VideoChannel[200] as an output
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: OpenLogicalChannel Received
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (UnknownRx) valid payloads 100
2013-10-01 09:26:58 DEBUG avc: pc[0]: Setting payload 100 on UnknownRx
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) rate set to 5Kbps
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) payload:100, packetLen:0 sampleRate:1
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) rate set to 6Kbps
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: ScreenManager: PERF 1735 Complete near[videoscreen.txt]
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: channel type fecc
2013-10-01 09:26:58 DEBUG jvm: pc[0]: JVM Memory,45,11297812,30645228,41943040
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: associating with RTPTxChan[9]
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: NativeAccess$4: Unrecognized Message [msg: VID videoroute set]
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM connection commChanAdded 39 9 feccRx "" ""
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: handleVideoInputAttachedMsg: msg = <m_category=0<m_identifier=79<m_parameters=cam1,attached,Component,1920,1080,25,Progressive>
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: rx chan RTPRxChan[9] added
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: handleVideoInputAttachedMsg : active camera = 1
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: adding FeccRxChan[0] as an output
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: handleVideoInputAttachedMsg : close the camera and reopen
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) Remote signalled channel has a dynamic RTP payload 100
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) valid payloads 100
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) dynamic payload:100
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel state 9 opening none H.224 39
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: h323nat: localIP = "10.5.148.240", farIP = "10.144.137.203", both are non-routable but on different LANs.
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: (FECCRx) Answering OLC, Receiving RTP port 49212, RTCP port 49213
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: (FECCRx) Setting src address to 10.5.148.240
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: (FECCRx) Channel active
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPRxChan[9]: opened
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel encrypt 9 none none
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel state 9 opened none H.224 39
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 INFO avc: pc[0]: H323Conn[3]: feccRx (H.224) state:opened
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 2 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: CamMan[0]: adding far camera[0] #0, connId=39
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: VID camera far added 21 0 "MPTZF" 39 1
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendRemovePanelFromFrameCmd - m_name: 7 m_videoEnabled: true
2013-10-01 09:26:58 DEBUG avc: pc[0]: FeccRxChan[0]: opened
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: VideoSource: setVideoStreamID: null
2013-10-01 09:26:58 DEBUG avc: pc[0]: Cameraapi: Picking earliest terminal[0, 39] as active terminal
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: removeOldStreamIDFromSource: set near video source stream ID = -1.
2013-10-01 09:26:58 DEBUG avc: pc[0]: uicmd: csapi.cameraapi.updatepresets <termid|39>
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: disableCamera: vinp
2013-10-01 09:26:58 DEBUG avc: pc[0]: Cameraapi: Active terminal changed to [0, 39]
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: CameraManager: allocateCamera - number: 1 people: true
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[9]: (FECCRx) error receiving RTP packet. Error No. 0
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: ContentDirector: handleCameraOpen cameraStreamID: vinp
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[9]: (FECCRx) unable to send empty UDP Keep Alive(err:-1)
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: VideoSource: setVideoStreamID: vinp
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[9]: (FECCRx) error receiving RTP packet. Error No. 0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoSource: setAspectRatio - AR: 1 srcName: NEAR
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[10]: (VidContentRx) error receiving RTP packet. Error No. 0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1280,height=720] [Disp - AR: 0 size: java.awt.Dimension[width=1280,height=720]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[12]: (VidPeopleRx) error receiving RTP packet. Error No. 0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendAddPanelToFrameCmd - width: 1280 height: 720
2013-10-01 09:26:58 WARNING avc: pc[0]: RTPRxChan[13]: (AudioRx) error receiving RTP packet. Error No. 0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: OpenLogicalChannelAck Received
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendAddPanelToFrameCmd - width: 1920 height: 1080
2013-10-01 09:26:58 DEBUG avc: pc[0]: ======attention! going to set the RTPPayload value to be value=100, isString=0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: updateMonitor1Panel: numOfPanelsOnMainMonitor: 2
2013-10-01 09:26:58 DEBUG avc: pc[0]: ======attention: we do find the RPTChan
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 4 name: FAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: ======attention: rtp payload is fecc
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1910 vout1 1920 1080 DVID 50 progressive pd01 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) valid payloads 7
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: updateMonitor2Panel: updating panel: 0 name: MONITOR_2_PANEL src: NEAR
2013-10-01 09:26:58 DEBUG avc: pc[0]: ======attention: yes, we will set it to 7
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) dynamic payload:7
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1911 vout2 1920 1080 DVID 50 interlaced vinp 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) Remote signalled channel has a dynamic RTP payload 100
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: DisplayManager: updateVCRVideoPanel
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) valid payloads 100
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1280,height=720] [Disp - AR: 0 size: java.awt.Dimension[width=1280,height=720]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) dynamic payload:100
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendAddPanelToFrameCmd - width: 1280 height: 720
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: (FECCTx) Channel active
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: updateMonitor1Panel: numOfPanelsOnMainMonitor: 2
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) sending RTP to address 10.144.137.224:50900 from port 49212
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 4 name: FAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: Remote IP Address is: 10.144.137.224
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1912 vout1 1920 1080 DVID 50 progressive pd01 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: (FECCTx) Setting src address to 10.5.148.240
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: updateMonitor2Panel: updating panel: 0 name: MONITOR_2_PANEL src: NEAR
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[9]: opened
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel encrypt 45 none none
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1913 vout2 1920 1080 DVID 50 interlaced vinp 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: NOTIFY: COMM commChannel state 45 opened none H.224 39
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: DisplayManager: updateVCRVideoPanel
2013-10-01 09:26:58 INFO avc: pc[0]: H323Conn[3]: feccTx (H.224) state:opened
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: updateMonitor1Panel: numOfPanelsOnMainMonitor: 2
2013-10-01 09:26:58 DEBUG avc: pc[0]: CamMan[0]: ERROR, can't add far camera, a camera is already at index 0
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 4 name: FAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: FeccTxChan[0]: opened
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1914 vout1 1920 1080 DVID 50 progressive pd01 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[12]: (VidPeopleTx) Remote FlowControlled channel rate to 320
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: updateMonitor2Panel: updating panel: 0 name: MONITOR_2_PANEL src: NEAR
2013-10-01 09:26:58 DEBUG avc: pc[0]: Conf[0]: PtoP conference and NO content, peopleTx:320Kbps contentTx:0Kbps peopleRx:320Kbps contentRx:320Kbps
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[12]: (VidPeopleTx) rate set to 320Kbps
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1915 vout2 1920 1080 DVID 50 interlaced vinp 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: Conf[0]: confInstance=0 and roleLabel=0
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: DisplayManager: updateVCRVideoPanel
2013-10-01 09:26:58 DEBUG avc: pc[0]: RTPTxChan[10]: (VidContentTx) rate set to 0Kbps
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1280,height=720] [Disp - AR: 0 size: java.awt.Dimension[width=1280,height=720]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: Conf[0]: confInstance=0 and roleLabel=1
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: VideoPanel: sendAddPanelToFrameCmd - width: 1280 height: 720
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoChannel[7]: Output first buffer: @ 91901120 w/ len 4147200
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: updateMonitor1Panel: numOfPanelsOnMainMonitor: 2
2013-10-01 09:26:58 DEBUG avc: ti[3]: VideoChannel[200]: Output first buffer: @ 91901131 w/ len 4147200
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 4 name: FAR]
2013-10-01 09:26:58 DEBUG avc: ti[3]: ImageProc[201]: Output first buffer: @ 91901131 w/ len 4147200
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1916 vout1 1920 1080 DVID 50 progressive pd01 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: Received remote's capabilities
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: updateMonitor2Panel: updating panel: 0 name: MONITOR_2_PANEL src: NEAR
2013-10-01 09:26:58 DEBUG avc: pc[0]: H.245: Remote endpoint supports H.239
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1920,height=1080] [Disp - AR: 1 size: java.awt.Dimension[width=1920,height=1080]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: H.245: Dual Stream Type: H.239
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: one src - route: videoroute set 1917 vout2 1920 1080 DVID 50 interlaced vinp 0 0 1920 1080 0 none
2013-10-01 09:26:58 DEBUG avc: pc[0]: H.245: Far Side supports H.263+ rtp headers
2013-10-01 09:26:58 DEBUG jvm: pc[0]: UI: asyn: DisplayManager: updateVCRVideoPanel
2013-10-01 09:26:58 DEBUG avc: pc[0]: H323Conn[3]: Received remote's capabilities - Different from last.
2013-10-01 09:26:58 INFO jvm: pc[0]: UI: asyn: aspectRatioAdjustment - cur op: none vid pnl dimen: java.awt.Dimension[width=1280,height=720] [Disp - AR: 0 size: java.awt.Dimension[width=1280,height=720]][SRC - AR: 1 type: 8 name: NEAR]
2013-10-01 09:26:58 DEBUG avc: pc[0]: Audio