Search Unity

Video Oculus GO Video 360 freeze & crash after reload

Discussion in 'Audio & Video' started by JoRangers, Dec 12, 2018.

  1. JoRangers

    JoRangers

    Joined:
    Nov 7, 2017
    Posts:
    26
    Hi,

    I am facing an issue with my video 360 on Oculus GO and unity.

    My issue :
    I have a simple scene with the unity video player inside (play on awake, loop check).
    After multiples reloads of this scene, the app freezes.
    From my tests, the crashes occur when the video player starts after 50 or 100 reloads of the scene or 15-30 minutes of use.

    I use Unity 2017.4.14
    I use the Oculus SDK 1.29

    Video:
    My video has a resolution of 3480x1920.
    Encode : h264 .mp4
    The video is displayed in equirectangular 360 in mono
    The video is captured with this asset :
    https://assetstore.unity.com/packages/tools/video/vr-panorama-360-pro-renderer-35102


    For the video player I have tested :

    - AVPro 1.9.6 (from asset store)
    https://assetstore.unity.com/packages/tools/video/avpro-video-56355

    - the built-in unity video player


    Projects with this issue :

    On my main project, I use AVPro.
    I have a video manager with the video player set as DontDestroyOnLoad
    In some scenes, I get the video manager and play a video or replay a video for these scenes.
    The issue appears around 30 videos played.
    In this case, the app freezes a few seconds, then return to the Oculus GO Home.

    I set my project settings with this link
    https://developer.oculus.com/blog/tech-note-unity-settings-for-mobile-vr/


    I though AVPro media player was the problem, so I decide to test with the unity Interactive 360 Sample Project ( https://assetstore.unity.com/packag...rojects/interactive-360-sample-project-108974 )
    I modify their example scene to have my video.
    In their project, unity uses a video player per scene.

    Unfortunately, the result is near the same as AVPro.
    The app freezes but it never return to the Oculus GO Home.


    I have tried to work on the video (decrease size and resolution) but the result is the same.



    Have you any ideas, tips or more information on this type of issue ?

    Thank you for the help !
    Best regards
     
    Last edited: Dec 12, 2018
    JoRangers, Dec 12, 2018
    #1

  2. JoRangers

    JoRangers

    Joined:
    Nov 7, 2017
    Posts:
    26
    Here is an adb logcat when my app freezes.
    In the log, the app freezes after this line :
    12-13 08:38:19.229 8862 8878 I Unity : NUMBER LOAD : 2

    I used the unity sample project with their video.
    Video details in attachment.

    12-13 08:38:10.923 8862 8918 I UtilPoller: GPU Util 0.353393 / CPU Util 0.612903 (avg 0.545761)
    12-13 08:38:11.908 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=58,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=2/2,1132/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=1053MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.44ms,App=4.31ms
    12-13 08:38:11.926 8862 8918 I UtilPoller: GPU Util 0.284103 / CPU Util 0.587629 (avg 0.513302)
    12-13 08:38:12.312 8862 8878 I Unity : loading scene:2D_Lookout
    12-13 08:38:12.312 8862 8878 I Unity :
    12-13 08:38:12.312 8862 8878 I Unity : (Filename: /Users/builduser/buildslave/unity/build/artifacts/generated/common/runtime/DebugBindings.gen.cpp Line: 51)
    12-13 08:38:12.312 8862 8878 I Unity :
    12-13 08:38:12.312 8862 8878 I Unity : NUMBER LOAD : 1
    12-13 08:38:12.312 8862 8878 I Unity :
    12-13 08:38:12.312 8862 8878 I Unity : (Filename: /Users/builduser/buildslave/unity/build/artifacts/generated/common/runtime/DebugBindings.gen.cpp Line: 51)
    12-13 08:38:12.312 8862 8878 I Unity :
    12-13 08:38:12.440 8862 8894 E WVMExtractor: Failed to open libwvm.so: dlopen failed: library "libwvm.so" not found
    12-13 08:38:12.451 551 894 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:12.512 551 4580 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:12.518 551 551 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:12.523 556 3254 I ExtendedExtractor: QTIParser is prefered .. flags 0
    12-13 08:38:12.524 556 3254 D MMParserExtractor: setExtraFlags called with flags 0 for paser instance e812ddc0
    12-13 08:38:12.525 556 3254 I ExtendedExtractor: ExtendedExtractor::create 0xe812ddc0
    12-13 08:38:12.554 556 891 E MM_OSAL : Atom::Atom _success is false due to size < DEFAULT_ATOM_SIZE
    12-13 08:38:12.575 556 891 E MM_OSAL : Atom::Atom _success is false due to size < DEFAULT_ATOM_SIZE
    12-13 08:38:12.647 557 557 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:12.651 8862 8948 I OMXClient: MuxOMX ctor
    12-13 08:38:12.658 554 554 I OMXMaster: makeComponentInstance(OMX.qcom.video.decoder.avc) in mediacodec process
    12-13 08:38:12.661 554 554 I OMX-VDEC-1080P: Video slvp perflock acquired
    12-13 08:38:12.662 554 554 I OMX-VDEC-1080P: component_init: OMX.qcom.video.decoder.avc : fd=8
    12-13 08:38:12.683 554 554 I OMX-VDEC-1080P: omx_vdec::component_init() success : fd=8
    12-13 08:38:12.685 8862 8948 I ExtendedACodec: setupVideoDecoder()
    12-13 08:38:12.685 8862 8948 I ExtendedACodec: vpp-enable search is 0 and value is 0
    12-13 08:38:12.689 554 883 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:12.689 554 883 E OMX-VDEC-1080P: Extension: OMX.google.android.index.configureVideoTunnelMode not implemented
    12-13 08:38:12.689 554 883 E OMX-VDEC-1080P: Extension: OMX.google.android.index.useAndroidNativeBuffer is supported
    12-13 08:38:12.714 554 883 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.716 554 554 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.717 554 554 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.719 8862 8948 I ExtendedACodec: Decoder will be in frame by frame mode
    12-13 08:38:12.723 554 5155 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.724 554 883 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.726 554 2823 E OMXNodeInstance: getParameter(22a02b6:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:12.754 554 5155 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.824 557 1843 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:12.827 8862 8954 I OMXClient: MuxOMX ctor
    12-13 08:38:12.832 554 2824 I OMXMaster: makeComponentInstance(OMX.google.aac.decoder) in mediacodec process
    12-13 08:38:12.913 8862 8917 I VrApi : FPS=45,Prd=53ms,Tear=0,Early=41,Stale=16,VSnc=1,Lat=1,Fov=0,CPU2/GPU=2/2,1132/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1804MHz,Free=996MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.45ms,App=4.23ms
    12-13 08:38:12.914 554 8955 I SoftAAC2: Reconfiguring decoder: 0->48000 Hz, 0->2 channels
    12-13 08:38:12.928 8862 8918 I UtilPoller: GPU Util 0.317662 / CPU Util 0.704082 (avg 0.627820)
    12-13 08:38:12.929 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.930 554 2843 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.931 554 2824 E OMXNodeInstance: getParameter(22a02b6:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:12.998 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:12.998 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.256 554 2824 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.282 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.283 554 2824 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.290 554 883 E OMXNodeInstance: getParameter(22a02b6:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:13.314 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.330 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.346 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.358 554 883 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.360 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.363 554 554 E OMXNodeInstance: getParameter(22a02b6:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:13.429 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.543 554 8950 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:13.695 554 5155 I OMX-VDEC-1080P: omx_vdec::component_deinit() complete
    12-13 08:38:13.702 554 5155 I OMX-VDEC-1080P: Exit OMX vdec Destructor: fd=8
    12-13 08:38:13.702 554 5155 I OMX-VDEC-1080P: Video slvp perflock released
    12-13 08:38:13.813 557 1130 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:13.815 8862 8961 I OMXClient: MuxOMX ctor
    12-13 08:38:13.821 554 554 I OMXMaster: makeComponentInstance(OMX.qcom.video.decoder.avc) in mediacodec process
    12-13 08:38:13.827 554 554 I OMX-VDEC-1080P: Video slvp perflock acquired
    12-13 08:38:13.829 554 554 I OMX-VDEC-1080P: component_init: OMX.qcom.video.decoder.avc : fd=8
    12-13 08:38:13.844 554 554 I OMX-VDEC-1080P: omx_vdec::component_init() success : fd=8
    12-13 08:38:13.850 8862 8960 I MediaCodec: [OMX.qcom.video.decoder.avc] setting surface generation to 9074689
    12-13 08:38:13.851 554 883 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:13.852 8862 8961 I ExtendedACodec: setupVideoDecoder()
    12-13 08:38:13.852 8862 8961 I ExtendedACodec: vpp-enable search is 0 and value is 0
    12-13 08:38:13.868 554 2824 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:13.868 554 2824 E OMX-VDEC-1080P: Extension: OMX.google.android.index.configureVideoTunnelMode not implemented
    12-13 08:38:13.868 554 2824 E OMX-VDEC-1080P: Extension: OMX.google.android.index.useAndroidNativeBuffer is supported
    12-13 08:38:13.869 8862 8961 I ExtendedACodec: Decoder will be in frame by frame mode
    12-13 08:38:13.878 554 5155 E OMX-VDEC-1080P: Does not handle dataspace request
    12-13 08:38:13.878 554 5155 E OMXNodeInstance: getConfig(22a02b8:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:13.904 8862 8961 D SurfaceUtils: set up nativeWindow 0xc33b3808 for 3840x2160, color 0x7fa30c06, rotation 0, usage 0x2002900
    12-13 08:38:13.906 8862 8917 I VrApi : FPS=49,Prd=53ms,Tear=0,Early=47,Stale=13,VSnc=1,Lat=1,Fov=0,CPU2/GPU=2/2,1132/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1804MHz,Free=1005MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.53ms,App=5.38ms
    12-13 08:38:13.935 8862 8918 I UtilPoller: GPU Util 0.404876 / CPU Util 0.930693 (avg 0.893071)
    12-13 08:38:13.940 557 1843 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:13.941 8862 8965 I OMXClient: MuxOMX ctor
    12-13 08:38:13.948 554 2843 I OMXMaster: makeComponentInstance(OMX.google.aac.decoder) in mediacodec process
    12-13 08:38:13.950 8862 8915 I Clocks : ReleaseClocks - start
    12-13 08:38:13.950 8862 8915 D VrApi : releaseSystemPerformanceStatic
    12-13 08:38:13.951 576 4711 D VrApiService: CPU_CLUSTER_0: No client requests exists, release clocks
    12-13 08:38:13.953 576 4711 D VrApiService: CPU_CLUSTER_1: No client requests exists, release clocks
    12-13 08:38:13.955 576 4711 D VrApiService: GPU: No client requests exists, release clocks
    12-13 08:38:13.956 576 4711 D VrApiService: CPUBW: No client requests exists, release clocks
    12-13 08:38:13.956 576 4711 D VrApiService: GPUBW: No client requests exists, release clocks
    12-13 08:38:13.957 8862 8915 I Clocks : ReleaseClocks - end (7.1 ms)
    12-13 08:38:13.957 8862 8915 I Clocks : LockClocks( cpu=3, gpu=2 ) - start
    12-13 08:38:13.958 8862 8915 D VrApi : Available levels: {GPU MIN, GPU MAX, CPU MIN, CPU MAX}
    12-13 08:38:13.958 8862 8915 D VrApi : -> / 0
    12-13 08:38:13.958 8862 8915 D VrApi : -> / 5
    12-13 08:38:13.958 8862 8915 D VrApi : -> / 0
    12-13 08:38:13.958 8862 8915 D VrApi : -> / 5
    12-13 08:38:13.958 8862 8915 I Clocks : CPU levels [0, 5]
    12-13 08:38:13.959 8862 8915 I Clocks : GPU levels [0, 5]
    12-13 08:38:13.959 8862 8915 D VrApi : setSystemPerformance cpu: 3 gpu: 2
    12-13 08:38:13.959 576 576 D VrApiService: CPU_CLUSTER_0: Client 8862 sends freq request [13, 13]
    12-13 08:38:13.959 576 576 D VrApiService: CPU_CLUSTER_0: Next Perf Level = (13, 13)
    12-13 08:38:13.960 576 576 D VrApiService: CPU_CLUSTER_1: Client 8862 sends freq request [13, 13]
    12-13 08:38:13.960 576 576 D VrApiService: CPU_CLUSTER_1: Next Perf Level = (13, 13)
    12-13 08:38:13.963 576 576 D VrApiService: CPUBW: Client 8862 sends freq request [8, 11]
    12-13 08:38:13.963 576 576 D VrApiService: CPUBW: Next Perf Level = (8, 11)
    12-13 08:38:13.965 576 576 D VrApiService: GPUBW: Client 8862 sends freq request [9, 11]
    12-13 08:38:13.965 576 576 D VrApiService: GPUBW: Next Perf Level = (9, 11)
    12-13 08:38:13.965 576 576 D VrApiService: GPU: Client 8862 sends freq request [2, 2]
    12-13 08:38:13.965 576 576 D VrApiService: GPU: Next Perf Level = (2, 2)
    12-13 08:38:13.966 8862 8915 I Clocks : LockClocks( cpu=3, gpu=2 ) - end (8.6 ms)
    12-13 08:38:14.068 554 883 E OMX-VDEC-1080P: Does not handle dataspace request
    12-13 08:38:14.069 554 883 E OMXNodeInstance: getConfig(22a02b8:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:14.093 8862 8961 D SurfaceUtils: set up nativeWindow 0xc33b3808 for 3840x2160, color 0x7fa30c06, rotation 0, usage 0x2002900
    12-13 08:38:14.175 554 2843 E OMX-VDEC-1080P: Does not handle dataspace request
    12-13 08:38:14.175 554 2843 E OMXNodeInstance: getConfig(22a02b8:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:14.182 554 2843 E OMX-VDEC-1080P: Does not handle dataspace request
    12-13 08:38:14.182 554 2843 E OMXNodeInstance: getConfig(22a02b8:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:14.463 8862 8915 I Clocks : ReleaseClocks - start
    12-13 08:38:14.463 8862 8915 D VrApi : releaseSystemPerformanceStatic
    12-13 08:38:14.463 576 4711 D VrApiService: CPU_CLUSTER_0: No client requests exists, release clocks
    12-13 08:38:14.466 576 4711 D VrApiService: CPU_CLUSTER_1: No client requests exists, release clocks
    12-13 08:38:14.467 576 4711 D VrApiService: GPU: No client requests exists, release clocks
    12-13 08:38:14.467 576 4711 D VrApiService: CPUBW: No client requests exists, release clocks
    12-13 08:38:14.468 576 4711 D VrApiService: GPUBW: No client requests exists, release clocks
    12-13 08:38:14.468 8862 8915 I Clocks : ReleaseClocks - end (5.5 ms)
    12-13 08:38:14.469 8862 8915 I Clocks : LockClocks( cpu=4, gpu=2 ) - start
    12-13 08:38:14.469 8862 8915 D VrApi : Available levels: {GPU MIN, GPU MAX, CPU MIN, CPU MAX}
    12-13 08:38:14.469 8862 8915 D VrApi : -> / 0
    12-13 08:38:14.469 8862 8915 D VrApi : -> / 5
    12-13 08:38:14.469 8862 8915 D VrApi : -> / 0
    12-13 08:38:14.469 8862 8915 D VrApi : -> / 5
    12-13 08:38:14.469 8862 8915 I Clocks : CPU levels [0, 5]
    12-13 08:38:14.469 8862 8915 I Clocks : GPU levels [0, 5]
    12-13 08:38:14.469 8862 8915 D VrApi : setSystemPerformance cpu: 4 gpu: 2
    12-13 08:38:14.470 576 576 D VrApiService: CPU_CLUSTER_0: Client 8862 sends freq request [17, 17]
    12-13 08:38:14.470 576 576 D VrApiService: CPU_CLUSTER_0: Next Perf Level = (17, 17)
    12-13 08:38:14.471 576 576 D VrApiService: CPU_CLUSTER_1: Client 8862 sends freq request [17, 17]
    12-13 08:38:14.471 576 576 D VrApiService: CPU_CLUSTER_1: Next Perf Level = (17, 17)
    12-13 08:38:14.471 576 576 D VrApiService: CPUBW: Client 8862 sends freq request [8, 11]
    12-13 08:38:14.471 576 576 D VrApiService: CPUBW: Next Perf Level = (8, 11)
    12-13 08:38:14.472 576 576 D VrApiService: GPUBW: Client 8862 sends freq request [9, 11]
    12-13 08:38:14.472 576 576 D VrApiService: GPUBW: Next Perf Level = (9, 11)
    12-13 08:38:14.472 576 576 D VrApiService: GPU: Client 8862 sends freq request [2, 2]
    12-13 08:38:14.472 576 576 D VrApiService: GPU: Next Perf Level = (2, 2)
    12-13 08:38:14.473 8862 8915 I Clocks : LockClocks( cpu=4, gpu=2 ) - end (4.1 ms)
    12-13 08:38:14.903 8862 8917 I VrApi : FPS=52,Prd=54ms,Tear=0,Early=25,Stale=10,VSnc=1,Lat=1,Fov=0,CPU2/GPU=4/2,1593/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=964MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.42ms,App=7.57ms
    12-13 08:38:14.937 8862 8918 I UtilPoller: GPU Util 0.469665 / CPU Util 0.712766 (avg 0.661081)
    12-13 08:38:15.905 8862 8917 I VrApi : FPS=59,Prd=54ms,Tear=0,Early=38,Stale=4,VSnc=1,Lat=1,Fov=0,CPU2/GPU=4/2,1593/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=963MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.51ms,App=7.23ms
    12-13 08:38:15.940 8862 8918 I UtilPoller: GPU Util 0.520076 / CPU Util 0.637363 (avg 0.601478)
    12-13 08:38:16.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=33,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=4/2,1593/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=962MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=8.03ms
    12-13 08:38:16.944 8862 8918 I UtilPoller: GPU Util 0.531998 / CPU Util 0.712871 (avg 0.664695)
    12-13 08:38:17.533 8862 8915 I Clocks : ReleaseClocks - start
    12-13 08:38:17.533 8862 8915 D VrApi : releaseSystemPerformanceStatic
    12-13 08:38:17.533 576 1847 D VrApiService: CPU_CLUSTER_0: No client requests exists, release clocks
    12-13 08:38:17.534 576 1847 D VrApiService: CPU_CLUSTER_1: No client requests exists, release clocks
    12-13 08:38:17.535 576 1847 D VrApiService: GPU: No client requests exists, release clocks
    12-13 08:38:17.535 576 1847 D VrApiService: CPUBW: No client requests exists, release clocks
    12-13 08:38:17.536 576 1847 D VrApiService: GPUBW: No client requests exists, release clocks
    12-13 08:38:17.536 8862 8915 I Clocks : ReleaseClocks - end (3.6 ms)
    12-13 08:38:17.537 8862 8915 I Clocks : LockClocks( cpu=3, gpu=2 ) - start
    12-13 08:38:17.538 8862 8915 D VrApi : Available levels: {GPU MIN, GPU MAX, CPU MIN, CPU MAX}
    12-13 08:38:17.538 8862 8915 D VrApi : -> / 0
    12-13 08:38:17.538 8862 8915 D VrApi : -> / 5
    12-13 08:38:17.538 8862 8915 D VrApi : -> / 0
    12-13 08:38:17.538 8862 8915 D VrApi : -> / 5
    12-13 08:38:17.538 8862 8915 I Clocks : CPU levels [0, 5]
    12-13 08:38:17.538 8862 8915 I Clocks : GPU levels [0, 5]
    12-13 08:38:17.538 8862 8915 D VrApi : setSystemPerformance cpu: 3 gpu: 2
    12-13 08:38:17.538 576 750 D VrApiService: CPU_CLUSTER_0: Client 8862 sends freq request [13, 13]
    12-13 08:38:17.538 576 750 D VrApiService: CPU_CLUSTER_0: Next Perf Level = (13, 13)
    12-13 08:38:17.539 576 750 D VrApiService: CPU_CLUSTER_1: Client 8862 sends freq request [13, 13]
    12-13 08:38:17.539 576 750 D VrApiService: CPU_CLUSTER_1: Next Perf Level = (13, 13)
    12-13 08:38:17.540 576 750 D VrApiService: CPUBW: Client 8862 sends freq request [8, 11]
    12-13 08:38:17.540 576 750 D VrApiService: CPUBW: Next Perf Level = (8, 11)
    12-13 08:38:17.543 576 750 D VrApiService: GPUBW: Client 8862 sends freq request [9, 11]
    12-13 08:38:17.543 576 750 D VrApiService: GPUBW: Next Perf Level = (9, 11)
    12-13 08:38:17.543 576 750 D VrApiService: GPU: Client 8862 sends freq request [2, 2]
    12-13 08:38:17.543 576 750 D VrApiService: GPU: Next Perf Level = (2, 2)
    12-13 08:38:17.545 8862 8915 I Clocks : LockClocks( cpu=3, gpu=2 ) - end (8.0 ms)
    12-13 08:38:17.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=29,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=962MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.61ms,App=8.22ms
    12-13 08:38:17.948 8862 8918 I UtilPoller: GPU Util 0.532095 / CPU Util 0.728261 (avg 0.680347)
    12-13 08:38:18.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=962MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:18.951 8862 8918 I UtilPoller: GPU Util 0.526420 / CPU Util 0.744898 (avg 0.708410)
    12-13 08:38:19.209 8862 8916 I Vsync : Vsync 144456: 1 x 17.40 ms (59.95 Hz, at 2407.632)
    12-13 08:38:19.227 8862 8916 I Vsync : Vsync 144457: 1 x 15.97 ms (59.95 Hz, at 2407.650)
    12-13 08:38:19.229 8862 8878 I Unity : loading scene:2D_Lookout
    12-13 08:38:19.229 8862 8878 I Unity :
    12-13 08:38:19.229 8862 8878 I Unity : (Filename: /Users/builduser/buildslave/unity/build/artifacts/generated/common/runtime/DebugBindings.gen.cpp Line: 51)
    12-13 08:38:19.229 8862 8878 I Unity :
    12-13 08:38:19.229 8862 8878 I Unity : NUMBER LOAD : 2
    12-13 08:38:19.229 8862 8878 I Unity :
    12-13 08:38:19.229 8862 8878 I Unity : (Filename: /Users/builduser/buildslave/unity/build/artifacts/generated/common/runtime/DebugBindings.gen.cpp Line: 51)
    12-13 08:38:19.229 8862 8878 I Unity :
    12-13 08:38:19.264 551 1610 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:19.305 551 551 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:19.311 551 1610 W ServiceManager: Permission failure: com.oma.drm.permission.ACCESS_OMA_DRM from uid=10221 pid=8862
    12-13 08:38:19.314 556 891 I ExtendedExtractor: QTIParser is prefered .. flags 0
    12-13 08:38:19.315 556 891 D MMParserExtractor: setExtraFlags called with flags 0 for paser instance e688c140
    12-13 08:38:19.315 556 891 I ExtendedExtractor: ExtendedExtractor::create 0xe688c140
    12-13 08:38:19.333 556 3255 E MM_OSAL : Atom::Atom _success is false due to size < DEFAULT_ATOM_SIZE
    12-13 08:38:19.344 556 3255 E MM_OSAL : Atom::Atom _success is false due to size < DEFAULT_ATOM_SIZE
    12-13 08:38:19.393 557 557 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:19.395 8862 8971 I OMXClient: MuxOMX ctor
    12-13 08:38:19.401 554 554 I OMXMaster: makeComponentInstance(OMX.qcom.video.decoder.avc) in mediacodec process
    12-13 08:38:19.404 554 554 I OMX-VDEC-1080P: Video slvp perflock released
    12-13 08:38:19.405 554 554 I OMX-VDEC-1080P: component_init: OMX.qcom.video.decoder.avc : fd=89
    12-13 08:38:19.412 554 554 I OMX-VDEC-1080P: omx_vdec::component_init() success : fd=89
    12-13 08:38:19.414 8862 8971 I ExtendedACodec: setupVideoDecoder()
    12-13 08:38:19.414 8862 8971 I ExtendedACodec: vpp-enable search is 0 and value is 0
    12-13 08:38:19.417 554 2843 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:19.417 554 2843 E OMX-VDEC-1080P: Extension: OMX.google.android.index.configureVideoTunnelMode not implemented
    12-13 08:38:19.417 554 2843 E OMX-VDEC-1080P: Extension: OMX.google.android.index.useAndroidNativeBuffer is supported
    12-13 08:38:19.437 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.439 554 554 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.439 554 5155 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.441 8862 8971 I ExtendedACodec: Decoder will be in frame by frame mode
    12-13 08:38:19.445 554 2824 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.447 554 2843 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.448 554 554 E OMXNodeInstance: getParameter(22a02ba:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:19.489 554 2824 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.654 557 1130 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:19.657 8862 8977 I OMXClient: MuxOMX ctor
    12-13 08:38:19.660 554 2823 I OMXMaster: makeComponentInstance(OMX.google.aac.decoder) in mediacodec process
    12-13 08:38:19.706 554 8978 I SoftAAC2: Reconfiguring decoder: 0->48000 Hz, 0->2 channels
    12-13 08:38:19.759 1261 1261 D VRPowerManagerService: processing message: sensor changed
    12-13 08:38:19.760 1261 1261 I VRPowerManagerService: transition from HEADSET_MOUNTED to HEADSET_UNMOUNTED
    12-13 08:38:19.768 1279 1314 D TrackingService: Received transition to STANDBY current is RUNNING
    12-13 08:38:19.768 1279 1314 D TrackingService: Setting power state to STANDBY
    12-13 08:38:19.772 1200 1255 D VRPowerManagerClient: Headset state: 3
    12-13 08:38:19.773 1200 1255 V ControllerNotifications: Handling unmount notification
    12-13 08:38:19.777 550 1883 D audio_hw_primary: adev_set_mic_mute state 0
    12-13 08:38:19.818 554 5155 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.818 554 5155 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.820 554 883 E OMXNodeInstance: getParameter(22a02ba:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:19.840 1505 1795 I VRLifecycleSessionManager: Stored session info.
    12-13 08:38:19.852 1505 1795 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:19.859 1505 1795 I PlatformPluginManager: setHeadsetMountState: false
    12-13 08:38:19.866 1505 1795 I OVRPlatform: [AudioManager] SetHeadsetMounted: false
    12-13 08:38:19.867 1505 1795 I OVRPlatform: [AudioManager] Current headsetMounted_ false
    12-13 08:38:19.867 1505 1795 I OVRPlatform: [AudioManager] Current headphoneStatus_: DISCONNECTED
    12-13 08:38:19.867 1505 1795 I OVRPlatform: [PartyChat] Setting connection MicrophoneMutedState MUTED
    12-13 08:38:19.867 1505 1795 I MountStatusPoller: mounted false
    12-13 08:38:19.868 1505 1876 I OVRPlatform: [PartyChat] Updating playback mode to 0
    12-13 08:38:19.869 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.870 554 1138 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:19.885 1505 1936 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:19.885 1505 1936 I MountStatusPoller: mounted false
    12-13 08:38:19.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1804MHz,Free=873MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:19.954 8862 8918 I UtilPoller: GPU Util 0.214097 / CPU Util 0.783505 (avg 0.713578)
    12-13 08:38:20.194 554 8973 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.197 554 2824 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.225 554 8973 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.258 554 8973 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.292 554 8973 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.296 554 883 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.297 554 2823 E OMXNodeInstance: getParameter(22a02ba:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:20.304 554 883 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.305 554 554 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.307 554 2843 E OMXNodeInstance: getParameter(22a02ba:qcom.decoder.avc, ??(0x7f000044)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:20.355 554 8973 E C2DColorConvert: unknown format passed for luma alignment number
    12-13 08:38:20.471 554 2823 I OMX-VDEC-1080P: omx_vdec::component_deinit() complete
    12-13 08:38:20.477 554 2823 I OMX-VDEC-1080P: Exit OMX vdec Destructor: fd=89
    12-13 08:38:20.477 554 2823 I OMX-VDEC-1080P: Video slvp perflock acquired
    12-13 08:38:20.546 557 896 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:20.548 8862 8983 I OMXClient: MuxOMX ctor
    12-13 08:38:20.552 554 5155 I OMXMaster: makeComponentInstance(OMX.qcom.video.decoder.avc) in mediacodec process
    12-13 08:38:20.554 554 5155 I OMX-VDEC-1080P: Video slvp perflock released
    12-13 08:38:20.554 554 5155 I OMX-VDEC-1080P: component_init: OMX.qcom.video.decoder.avc : fd=89
    12-13 08:38:20.598 554 5155 I OMX-VDEC-1080P: omx_vdec::component_init() success : fd=89
    12-13 08:38:20.603 8862 8982 I MediaCodec: [OMX.qcom.video.decoder.avc] setting surface generation to 9074690
    12-13 08:38:20.604 554 2824 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:20.604 8862 8983 I ExtendedACodec: setupVideoDecoder()
    12-13 08:38:20.604 8862 8983 I ExtendedACodec: vpp-enable search is 0 and value is 0
    12-13 08:38:20.615 554 2823 E OMX-VDEC-1080P: Extension: OMX.google.android.index.storeANWBufferInMetadata not implemented
    12-13 08:38:20.615 554 2823 E OMX-VDEC-1080P: Extension: OMX.google.android.index.configureVideoTunnelMode not implemented
    12-13 08:38:20.615 554 2823 E OMX-VDEC-1080P: Extension: OMX.google.android.index.useAndroidNativeBuffer is supported
    12-13 08:38:20.617 8862 8983 I ExtendedACodec: Decoder will be in frame by frame mode
    12-13 08:38:20.623 554 883 E OMX-VDEC-1080P: Does not handle dataspace request
    12-13 08:38:20.623 554 883 E OMXNodeInstance: getConfig(22a02bc:qcom.decoder.avc, ??(0x7f000062)) ERROR: UnsupportedSetting(0x80001019)
    12-13 08:38:20.660 8862 8983 D SurfaceUtils: set up nativeWindow 0xc6220808 for 3840x2160, color 0x7fa30c06, rotation 0, usage 0x2002900
    12-13 08:38:20.680 557 557 I MediaPlayerService: MediaPlayerService::getOMX
    12-13 08:38:20.682 8862 8987 I OMXClient: MuxOMX ctor
    12-13 08:38:20.685 554 1138 I OMXMaster: makeComponentInstance(OMX.google.aac.decoder) in mediacodec process
    12-13 08:38:20.905 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:20.957 8862 8918 I UtilPoller: GPU Util 0.142160 / CPU Util 0.778947 (avg 0.703392)
    12-13 08:38:21.906 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:21.959 8862 8918 I UtilPoller: GPU Util 0.078230 / CPU Util 0.479592 (avg 0.209101)
    12-13 08:38:22.905 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:22.961 8862 8918 I UtilPoller: GPU Util 0.080117 / CPU Util 0.468750 (avg 0.244647)
    12-13 08:38:23.905 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:23.963 8862 8918 I UtilPoller: GPU Util 0.082356 / CPU Util 0.446809 (avg 0.200795)
    12-13 08:38:24.256 1261 1261 D VRPowerManagerService: processing message: sensor changed
    12-13 08:38:24.256 1261 1261 I VRPowerManagerService: transition from HEADSET_UNMOUNTED to HEADSET_MOUNTED
    12-13 08:38:24.258 1200 1255 D VRPowerManagerClient: Headset state: 2
    12-13 08:38:24.258 1200 1255 V ControllerNotifications: Headset was mounted, requesting that notifications are enabled
    12-13 08:38:24.258 1200 1255 V ControllerNotifications: Queueing enable of notifications in 5000ms
    12-13 08:38:24.259 550 550 D audio_hw_primary: adev_set_mic_mute state 0
    12-13 08:38:24.259 1279 1314 D TrackingService: Received transition to RUNNING current is STANDBY
    12-13 08:38:24.259 1279 1314 D TrackingService: Setting power state to RUNNING
    12-13 08:38:24.259 1279 1740 I [ORIENTATION:SensorFusionImpl-H]: SensorFusion 2: ResetCorrections
    12-13 08:38:24.340 1505 1795 W VRLifecycleManager: onHeadsetMounted
    12-13 08:38:24.346 1505 1795 I PlatformPluginManager: setHeadsetMountState: true
    12-13 08:38:24.347 1505 1795 I OVRPlatform: [AudioManager] SetHeadsetMounted: true
    12-13 08:38:24.347 1505 1795 I OVRPlatform: [AudioManager] Current headsetMounted_ true
    12-13 08:38:24.347 1505 1795 I OVRPlatform: [AudioManager] Current headphoneStatus_: DISCONNECTED
    12-13 08:38:24.347 1505 1795 I OVRPlatform: [PartyChat] Setting connection MicrophoneMutedState UNMUTED
    12-13 08:38:24.348 1505 1876 I OVRPlatform: [PartyChat] Updating playback mode to 1
    12-13 08:38:24.348 1505 1795 I MountStatusPoller: mounted true
    12-13 08:38:24.385 1505 1936 W VRLifecycleManager: onHeadsetMounted
    12-13 08:38:24.385 1505 1936 I MountStatusPoller: mounted true
    12-13 08:38:24.906 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:24.965 8862 8918 I UtilPoller: GPU Util 0.080769 / CPU Util 0.468085 (avg 0.266641)
    12-13 08:38:25.906 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:25.968 8862 8918 I UtilPoller: GPU Util 0.081741 / CPU Util 0.453608 (avg 0.216648)
    12-13 08:38:26.053 1261 1261 D VRPowerManagerService: processing message: sensor changed
    12-13 08:38:26.053 1261 1261 I VRPowerManagerService: transition from HEADSET_MOUNTED to HEADSET_UNMOUNTED
    12-13 08:38:26.056 1200 1248 D VRPowerManagerClient: Headset state: 3
    12-13 08:38:26.056 1200 1248 V ControllerNotifications: Handling unmount notification
    12-13 08:38:26.057 550 956 D audio_hw_primary: adev_set_mic_mute state 0
    12-13 08:38:26.057 1279 1764 D TrackingService: Received transition to STANDBY current is RUNNING
    12-13 08:38:26.057 1279 1764 D TrackingService: Setting power state to STANDBY
    12-13 08:38:26.087 1505 1795 I VRLifecycleSessionManager: Stored session info.
    12-13 08:38:26.089 1505 1795 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:26.091 1505 1795 I PlatformPluginManager: setHeadsetMountState: false
    12-13 08:38:26.092 1505 1795 I OVRPlatform: [AudioManager] SetHeadsetMounted: false
    12-13 08:38:26.092 1505 1795 I OVRPlatform: [AudioManager] Current headsetMounted_ false
    12-13 08:38:26.092 1505 1795 I OVRPlatform: [AudioManager] Current headphoneStatus_: DISCONNECTED
    12-13 08:38:26.092 1505 1795 I OVRPlatform: [PartyChat] Setting connection MicrophoneMutedState MUTED
    12-13 08:38:26.092 1505 1795 I MountStatusPoller: mounted false
    12-13 08:38:26.093 1505 1876 I OVRPlatform: [PartyChat] Updating playback mode to 0
    12-13 08:38:26.135 1505 1936 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:26.135 1505 1936 I MountStatusPoller: mounted false
    12-13 08:38:26.907 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:26.970 8862 8918 I UtilPoller: GPU Util 0.079447 / CPU Util 0.479167 (avg 0.230175)
    12-13 08:38:27.767 970 1034 D WifiStateMachine: updateCapabilities for config:InCubfalse,false
    12-13 08:38:27.770 1191 1191 D com.oculus.telemetry.collector.WiFiCollector: action = android.net.wifi.RSSI_CHANGED
    12-13 08:38:27.906 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:27.972 8862 8918 I UtilPoller: GPU Util 0.078523 / CPU Util 0.428571 (avg 0.229223)
    12-13 08:38:28.908 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:28.974 8862 8918 I UtilPoller: GPU Util 0.078501 / CPU Util 0.463918 (avg 0.207053)
    12-13 08:38:29.907 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:29.976 8862 8918 I UtilPoller: GPU Util 0.078491 / CPU Util 0.479592 (avg 0.235379)
    12-13 08:38:30.303 8862 8916 I Vsync : Vsync 145121: 1 x 17.62 ms (59.94 Hz, at 2418.726)
    12-13 08:38:30.321 8862 8916 I Vsync : Vsync 145122: 1 x 15.73 ms (59.94 Hz, at 2418.744)
    12-13 08:38:30.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:30.978 8862 8918 I UtilPoller: GPU Util 0.078342 / CPU Util 0.468085 (avg 0.231876)
    12-13 08:38:31.908 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:31.980 8862 8918 I UtilPoller: GPU Util 0.078619 / CPU Util 0.484848 (avg 0.236496)
    12-13 08:38:32.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:32.982 8862 8918 I UtilPoller: GPU Util 0.078566 / CPU Util 0.477778 (avg 0.270829)
    12-13 08:38:33.908 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:33.984 8862 8918 I UtilPoller: GPU Util 0.078237 / CPU Util 0.463158 (avg 0.235011)
    12-13 08:38:34.888 1191 1191 I com.oculus.telemetry.service.TelemetryService: flushing collectors
    12-13 08:38:34.889 1191 1191 I com.oculus.telemetry.core.EventRecorder: Event flushed: oculus_os_thermal {"build":"oculus\/vr_pacific\/pacific:7.1.2\/N2G48H\/178890.1870.154:user\/release-keys","flavor":"user","realtime_ms":2423310,"uptime_ms":2423310,"front_plate_temperature_min":31,"front_plate_temperature_max":32,"front_plate_temperature_total":957,"front_plate_temperature_total_square":30531,"cpu_temperature_min":44,"cpu_temperature_max":44,"cpu_temperature_total":1517,"cpu_temperature_total_square":77401,"gpu_temperature_min":43,"gpu_temperature_max":43,"gpu_temperature_total":1408,"gpu_temperature_total_square":66254,"nb_samples":30}
    12-13 08:38:34.893 1191 1191 D com.oculus.telemetry.collector.WiFiCollector: wifiQualityEvent = {"build":"oculus\/vr_pacific\/pacific:7.1.2\/N2G48H\/178890.1870.154:user\/release-keys","flavor":"user","realtime_ms":2423314,"uptime_ms":2423315,"is_connected":true,"connecting_time":2150228,"network_type":"WIFI 0.0GHz","rssi_dbm":-55,"wifi_score":-1}
    12-13 08:38:34.893 1191 1191 I com.oculus.telemetry.core.EventRecorder: Event flushed: oculus_mobile_wifi_quality {"build":"oculus\/vr_pacific\/pacific:7.1.2\/N2G48H\/178890.1870.154:user\/release-keys","flavor":"user","realtime_ms":2423314,"uptime_ms":2423315,"is_connected":true,"connecting_time":2150228,"network_type":"WIFI 0.0GHz","rssi_dbm":-55,"wifi_score":-1}
    12-13 08:38:34.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:34.986 8862 8918 I UtilPoller: GPU Util 0.078204 / CPU Util 0.457447 (avg 0.221657)
    12-13 08:38:35.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:35.988 8862 8918 I UtilPoller: GPU Util 0.078345 / CPU Util 0.463918 (avg 0.222166)
    12-13 08:38:36.879 970 1034 D WifiStateMachine: updateCapabilities for config:InCubfalse,false
    12-13 08:38:36.881 1191 1191 D com.oculus.telemetry.collector.WiFiCollector: action = android.net.wifi.RSSI_CHANGED
    12-13 08:38:36.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:36.990 8862 8918 I UtilPoller: GPU Util 0.077423 / CPU Util 0.445652 (avg 0.228684)
    12-13 08:38:37.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:37.941 1261 1261 D VRPowerManagerService: processing message: sensor changed
    12-13 08:38:37.941 1261 1261 I VRPowerManagerService: transition from HEADSET_UNMOUNTED to HEADSET_MOUNTED
    12-13 08:38:37.942 1200 1255 D VRPowerManagerClient: Headset state: 2
    12-13 08:38:37.942 1200 1255 V ControllerNotifications: Headset was mounted, requesting that notifications are enabled
    12-13 08:38:37.942 1200 1255 V ControllerNotifications: Queueing enable of notifications in 5000ms
    12-13 08:38:37.942 1279 1764 D TrackingService: Received transition to RUNNING current is STANDBY
    12-13 08:38:37.942 1279 1764 D TrackingService: Setting power state to RUNNING
    12-13 08:38:37.943 1279 1740 I [ORIENTATION:SensorFusionImpl-H]: SensorFusion 2: ResetCorrections
    12-13 08:38:37.945 550 957 D audio_hw_primary: adev_set_mic_mute state 0
    12-13 08:38:37.992 8862 8918 I UtilPoller: GPU Util 0.072835 / CPU Util 0.445652 (avg 0.222406)
    12-13 08:38:38.089 1505 1795 W VRLifecycleManager: onHeadsetMounted
    12-13 08:38:38.093 1505 1795 I PlatformPluginManager: setHeadsetMountState: true
    12-13 08:38:38.094 1505 1795 I OVRPlatform: [AudioManager] SetHeadsetMounted: true
    12-13 08:38:38.094 1505 1795 I OVRPlatform: [AudioManager] Current headsetMounted_ true
    12-13 08:38:38.094 1505 1795 I OVRPlatform: [AudioManager] Current headphoneStatus_: DISCONNECTED
    12-13 08:38:38.094 1505 1795 I OVRPlatform: [PartyChat] Setting connection MicrophoneMutedState UNMUTED
    12-13 08:38:38.094 1505 1795 I MountStatusPoller: mounted true
    12-13 08:38:38.094 1505 1876 I OVRPlatform: [PartyChat] Updating playback mode to 1
    12-13 08:38:38.134 1505 1936 W VRLifecycleManager: onHeadsetMounted
    12-13 08:38:38.134 1505 1936 I MountStatusPoller: mounted true
    12-13 08:38:38.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:38.994 8862 8918 I UtilPoller: GPU Util 0.086290 / CPU Util 0.479167 (avg 0.229982)
    12-13 08:38:39.639 1261 1261 D VRPowerManagerService: processing message: sensor changed
    12-13 08:38:39.640 1261 1261 I VRPowerManagerService: transition from HEADSET_MOUNTED to HEADSET_UNMOUNTED
    12-13 08:38:39.642 1279 1310 D TrackingService: Received transition to STANDBY current is RUNNING
    12-13 08:38:39.642 1279 1310 D TrackingService: Setting power state to STANDBY
    12-13 08:38:39.642 1200 1248 D VRPowerManagerClient: Headset state: 3
    12-13 08:38:39.642 1200 1248 V ControllerNotifications: Handling unmount notification
    12-13 08:38:39.643 550 1883 D audio_hw_primary: adev_set_mic_mute state 0
    12-13 08:38:39.837 1505 1795 I VRLifecycleSessionManager: Stored session info.
    12-13 08:38:39.840 1505 1795 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:39.842 1505 1795 I PlatformPluginManager: setHeadsetMountState: false
    12-13 08:38:39.843 1505 1795 I OVRPlatform: [AudioManager] SetHeadsetMounted: false
    12-13 08:38:39.843 1505 1795 I OVRPlatform: [AudioManager] Current headsetMounted_ false
    12-13 08:38:39.843 1505 1795 I OVRPlatform: [AudioManager] Current headphoneStatus_: DISCONNECTED
    12-13 08:38:39.843 1505 1795 I OVRPlatform: [PartyChat] Setting connection MicrophoneMutedState MUTED
    12-13 08:38:39.843 1505 1795 I MountStatusPoller: mounted false
    12-13 08:38:39.843 1505 1876 I OVRPlatform: [PartyChat] Updating playback mode to 0
    12-13 08:38:39.884 1505 1936 W VRLifecycleManager: onHeadsetUnmounted
    12-13 08:38:39.885 1505 1936 I MountStatusPoller: mounted false
    12-13 08:38:39.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:39.996 8862 8918 I UtilPoller: GPU Util 0.078773 / CPU Util 0.439560 (avg 0.211998)
    12-13 08:38:40.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:40.999 8862 8918 I UtilPoller: GPU Util 0.083876 / CPU Util 0.430233 (avg 0.232785)
    12-13 08:38:41.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:42.001 8862 8918 I UtilPoller: GPU Util 0.082632 / CPU Util 0.458333 (avg 0.224143)
    12-13 08:38:42.908 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=916MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:43.003 8862 8918 I UtilPoller: GPU Util 0.082822 / CPU Util 0.463158 (avg 0.196851)
    12-13 08:38:43.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:44.005 8862 8918 I UtilPoller: GPU Util 0.082300 / CPU Util 0.445652 (avg 0.220093)
    12-13 08:38:44.909 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:45.008 8862 8918 I UtilPoller: GPU Util 0.082620 / CPU Util 0.450549 (avg 0.208167)
    12-13 08:38:45.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:46.010 8862 8918 I UtilPoller: GPU Util 0.082599 / CPU Util 0.451613 (avg 0.222759)
    12-13 08:38:46.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:47.012 8862 8918 I UtilPoller: GPU Util 0.083066 / CPU Util 0.463158 (avg 0.210735)
    12-13 08:38:47.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:48.014 8862 8918 I UtilPoller: GPU Util 0.082704 / CPU Util 0.433333 (avg 0.209871)
    12-13 08:38:48.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:49.016 8862 8918 I UtilPoller: GPU Util 0.082353 / CPU Util 0.439560 (avg 0.209707)
    12-13 08:38:49.910 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:50.018 8862 8918 I UtilPoller: GPU Util 0.082905 / CPU Util 0.457447 (avg 0.206460)
    12-13 08:38:50.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=915MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:51.020 8862 8918 I UtilPoller: GPU Util 0.082956 / CPU Util 0.484211 (avg 0.299968)
    12-13 08:38:51.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:52.022 8862 8918 I UtilPoller: GPU Util 0.082440 / CPU Util 0.439560 (avg 0.239481)
    12-13 08:38:52.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:53.024 8862 8918 I UtilPoller: GPU Util 0.082738 / CPU Util 0.484536 (avg 0.251854)
    12-13 08:38:53.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:54.028 8862 8918 I UtilPoller: GPU Util 0.082743 / CPU Util 0.433333 (avg 0.190961)
    12-13 08:38:54.912 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:55.030 8862 8918 I UtilPoller: GPU Util 0.082417 / CPU Util 0.458333 (avg 0.211929)
    12-13 08:38:55.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:56.032 8862 8918 I UtilPoller: GPU Util 0.082617 / CPU Util 0.473684 (avg 0.227373)
    12-13 08:38:56.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=914MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:57.034 8862 8918 I UtilPoller: GPU Util 0.082850 / CPU Util 0.428571 (avg 0.195481)
    12-13 08:38:57.912 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:58.036 8862 8918 I UtilPoller: GPU Util 0.082824 / CPU Util 0.468085 (avg 0.231420)
    12-13 08:38:58.104 1191 1229 I com.oculus.telemetry.core.EventRecorder: Event flushed: oculus_go_tracking_stats {"accel_operating_temperature":"{\"mean\":[40.724],\"max\":[41.5],\"min\":[40],\"std\":[0.461513],\"count\":[302427]}","accel_packet_loss":"{\"mean\":[1.32813],\"max\":[2],\"min\":[1],\"std\":[0.471376],\"count\":[128]}","accel_runtime":"{\"mean\":[18.4408],\"max\":[1641],\"min\":[1],\"std\":[18.6495],\"count\":[302599]}","accel_snaps":"{\"mean\":[null],\"max\":[null],\"min\":[null],\"std\":[null],\"count\":[0],\"count_from_saturation\":[0]}","accel_time_filter_counter":"{\"resets\":[0],\"updates\":[302427],\"packets_on_time\":[277883],\"samples_on_time\":[301442],\"repeated_samples\":[0],\"unsynced_samples\":[0]}","accel_time_filter_jitter":"{\"mean\":[3.33997e-05],\"max\":[0.000940949],\"min\":[-0.000728776],\"std\":[9.61123e-05],\"count\":[302427]}","duration_seconds":300.0002584280001,"gravity_correction_percent":"{\"mean\":[0.995917],\"max\":[1],\"min\":[0.404361],\"std\":[0.0392681],\"count\":[300]}","gravity_corrections":"{\"mean\":[0.000682375],\"max\":[0.00657812],\"min\":[3.08444e-06],\"std\":[0.00109094],\"count\":[300]}","gyro_factory_calibration_offset_correlation":[0.9510037899017334,-0.6962954998016357,-0.8640968799591064],"gyro_factory_calibration_offset_intercept":[0.0006866551702842116,0.0006963436608202755,0.0003392128855921328],"gyro_factory_calibration_offset_residual":[0.0002415925118839368,0.0002859884116332978,0.0001014100125757977],"gyro_factory_calibration_offset_slope":[0.0001358638110104948,-5.07240038132295e-05,-3.183043008903041e-05],"gyro_loaded_calibration_temperature":"{\"mean\":[38.5496],\"max\":[56.4545],\"min\":[20.75],\"std\":[10.7274],\"count\":[37]}","gyro_online_calibration_offset_correlation":[0.9213774800300598,-0.9539828300476074,-0.9451553821563721],"gyro_online_calibration_offset_intercept":[0.002127842977643013,0.0007472500437870622,3.873244349961169e-05],"gyro_online_calibration_offset_residual":[0.0004908202681690454,0.0002005797432502732,9.146119555225596e-05],"gyro_online_calibration_offset_slope":[0.0001099603323382325,-6.030624354025349e-05,-2.501219023542944e-05],"gyro_online_calibration_temperature":"{\"mean\":[46.0455],\"max\":[46.5],\"min\":[46],\"std\":[0.147122],\"count\":[22]}","gyro_online_difference":"{\"mean\":[0.000163514],\"max\":[0.00020854],\"min\":[0.000110751],\"std\":[2.31623e-05],\"count\":[22]}","gyro_operating_temperature":"{\"mean\":[46.3963],\"max\":[47],\"min\":[46],\"std\":[0.384264],\"count\":[299565]}","gyro_packet_loss":"{\"mean\":[1.38922],\"max\":[2],\"min\":[1],\"std\":[0.48904],\"count\":[167]}","gyro_runtime":"{\"mean\":[18.2942],\"max\":[3662],\"min\":[0],\"std\":[21.8355],\"count\":[299797]}","gyro_time_filter_counter":"{\"resets\":[0],\"updates\":[299565],\"packets_on_time\":[250571],\"samples_on_time\":[298401],\"repeated_samples\":[0],\"unsynced_samples\":[0]}","gyro_time_filter_jitter":"{\"mean\":[4.56155e-05],\"max\":[0.000950144],\"min\":[-0.000781281],\"std\":[0.000167751],\"count\":[299565]}","mag_centroid_stats":"{\"mean\":[null],\"max\":[null],\"min\":[null],\"std\":[null],\"count\":[0],\"max_movement\":[0]}","mag_packet_loss":"{\"mean\":[null],\"max\":[null],\"min\":[null],\"std\":[null],\"count\":[0]}","mag_runtime":"{\"mean\":[49.8992],\"max\":[881],\"min\":[9],\"std\":[45.4374],\"count\":[29858]}","mag_snaps":"{\"mean\":[null],\"max\":[null],\"min\":[null],\"std\":[null],\"count\":[0]}","mag_time_filter_counter":"{\"resets\":[0],\"updates\":[29858],\"packets_on_time\":[29858],\"samples_on_time\":[29858],\"repeated_samples\":[0],\"unsynced_samples\":[0]}","mag_time_filter_jitter":"{\"mean\":[-1.06602e-06],\"max\":[0.00106393],\"min\":[-0.000220243],\"std\":[6.23513e-05],\"count\":[29858]}","number_of_accel_updates":302427,"number_of_gyro_online_cal_updates":22,"number_of_gyro_saturations":0,"number_of_gyro_updates":299565,"number_of_mag_online_cal_updates":0,"number_of_mag_updates":29858,"number_of_recenterings":17,"realtime_ms":46358,"start_time_since_epoch":1544684336,"tracked_device":"GoHeadset","uptime_ms":46358
    12-13 08:38:58.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:38:59.038 8862 8918 I UtilPoller: GPU Util 0.082506 / CPU Util 0.445652 (avg 0.208063)
    12-13 08:38:59.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:00.041 8862 8918 I UtilPoller: GPU Util 0.082933 / CPU Util 0.433333 (avg 0.217824)
    12-13 08:39:00.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:01.043 8862 8918 I UtilPoller: GPU Util 0.083238 / CPU Util 0.572917 (avg 0.350314)
    12-13 08:39:01.813 970 1973 D DhcpClient: Unicasting DHCPREQUEST ciaddr=192.168.88.34 request=0.0.0.0 serverid=null to /10.20.47.1:67
    12-13 08:39:01.817 970 1975 D DhcpClient: Received packet: 2c:26:17:03:06:cd ACK: your new IP /192.168.88.34, netmask /255.255.255.0, gateways [/192.168.88.1] DNS servers: /8.8.8.8 , lease time 600
    12-13 08:39:01.818 970 1973 D DhcpClient: Renewed lease: IP address 192.168.88.34/24 Gateway 192.168.88.1 DNS servers: [ 8.8.8.8 ] Domains DHCP server /10.20.47.1 Vendor info null lease 600 seconds
    12-13 08:39:01.830 970 1034 D WifiStateMachine: updateCapabilities for config:InCubfalse,false
    12-13 08:39:01.836 970 1973 D DhcpClient: Scheduling renewal in 299s
    12-13 08:39:01.836 970 1973 D DhcpClient: Scheduling rebind in 524s
    12-13 08:39:01.836 970 1973 D DhcpClient: Scheduling expiry in 599s
    12-13 08:39:01.845 1191 1191 D com.oculus.telemetry.collector.WiFiCollector: action = android.net.wifi.STATE_CHANGE
    12-13 08:39:01.849 1575 1575 D [OAR] ShellControlBroadcastReceiver: onReceive with sAppPtr 3655924544 and action android.net.wifi.STATE_CHANGE
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: 0xe534a328 msg: broadcastIntent:
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: linkProperties = {InterfaceName: wlan0 LinkAddresses: [fe80::2e26:17ff:fe03:6cd/64,192.168.88.34/24,] Routes: [fe80::/64 -> :: wlan0,192.168.88.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.88.1 wlan0,] DnsAddresses: [8.8.8.8,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: wifiInfo = SSID: InCub, BSSID: 00:c8:8b:26:4e:8c, MAC: 02:00:00:00:00:00, Supplicant state: COMPLETED, RSSI: -58, Link speed: 360Mbps, Frequency: 5240MHz, Net ID: 5, Metered hint: false, score: 60
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: bssid = 00:c8:8b:26:4e:8c
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: custom_connected = 1
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: intent_action = android.net.wifi.STATE_CHANGE
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: networkInfo = [type: WIFI[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "InCub", failover: false, available: true, roaming: false, metered: false]
    12-13 08:39:01.852 1575 1653 I [OAR] ShellApp: Network Connectivity Changed - connected state 1
    12-13 08:39:01.878 2397 2397 D [OASR] ShellReceiver: onReceive with appPtr 0 and action android.net.wifi.STATE_CHANGE
    12-13 08:39:01.912 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:02.045 8862 8918 I UtilPoller: GPU Util 0.082685 / CPU Util 0.471910 (avg 0.280692)
    12-13 08:39:02.274 1575 1575 D [OAR] LowStorageNotificationScheduler: Storage Checks: Internal Free (18683781120)
    12-13 08:39:02.912 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:03.047 8862 8918 I UtilPoller: GPU Util 0.082810 / CPU Util 0.451613 (avg 0.209119)
    12-13 08:39:03.912 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:04.049 8862 8918 I UtilPoller: GPU Util 0.082989 / CPU Util 0.440860 (avg 0.185503)
    12-13 08:39:04.911 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:05.051 8862 8918 I UtilPoller: GPU Util 0.082627 / CPU Util 0.456522 (avg 0.233710)
    12-13 08:39:05.913 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:06.053 8862 8918 I UtilPoller: GPU Util 0.082550 / CPU Util 0.428571 (avg 0.194173)
    12-13 08:39:06.920 1505 1686 I SDKLoggingService: Unable to find AppId for package (com.vr.Interactive360Sample_unityvideo)
    12-13 08:39:06.920 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=913MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:07.055 8862 8918 I UtilPoller: GPU Util 0.083111 / CPU Util 0.445652 (avg 0.218590)
    12-13 08:39:07.914 8862 8917 I VrApi : FPS=60,Prd=54ms,Tear=0,Early=30,Stale=0,VSnc=1,Lat=1,Fov=0,CPU2/GPU=3/2,1286/315MHz,OC=F,TA=E/0/0,SP=N/N/N,Mem=1017MHz,Free=912MB,PSM=0,PLS=0,Temp=24.7C/25.0C,TW=1.60ms,App=7.89ms
    12-13 08:39:08.059 8862 8918 I UtilPoller: GPU Util 0.084282 / CPU Util 0.462366 (avg 0.190922)
     

    Attached Files:

    JoRangers, Dec 13, 2018
    #2

  3. unity_bVyLGuBKm2hZew

    unity_bVyLGuBKm2hZew

    Joined:
    Dec 19, 2018
    Posts:
    1
    I am having kind of the the same issue. After the splash screen, my app loads a video with avpro.

    Sometimes it crashes, exiting the app and going to the oculus home menu.

    Other times, it freezes the screen, but the video keeps playing in the background. I can hear it and I debuged the play time, so it is running.

    And other times it works completely fine.

    I'll let you know if I find anything.
     
    unity_bVyLGuBKm2hZew, Dec 19, 2018
    #3
    JoRangers likes this.

  4. JoRangers

    JoRangers

    Joined:
    Nov 7, 2017
    Posts:
    26
    Hi, I finally found solution about my problem.
    I stopped my test after more than 15 000 reloads of my video without any crashes.

    Here is my setup

    Unity 2017.4.14f1

    Oculus Utilities v1.30.0, OVRPlugin v1.30.0

    - AVPro
    -> version 1.9.8
    Currently this version has a bug with ExoPlayer, you need to switch to Media Player.
    I asked the dev, this bug will be corrected in the version 1.9.10
    https://forum.unity.com/threads/rel...playback-solution.385611/page-61#post-4080577


    AVPro Media Player Script need to be Persistent (the media player will be automatically inside the DontDestroyOnLoad scene)
    Then you can't have a Media Player in each scene, you have to manage to keep only one global Media Player as a singleton.
    https://forum.unity.com/threads/rel...playback-solution.385611/page-56#post-3837658
    (Solution 3)


    To create the video, we used : VR Panorama 360 PRO Renderer
    https://assetstore.unity.com/packages/tools/video/vr-panorama-360-pro-renderer-35102
    To optimize the video, you can use Handbrake or others software.


    All my others settings for AvPro, Oculus, Unity Player Settings, Unity Graphics are on the attachments

    Hope these informations will help you.
     

    Attached Files:

    JoRangers, Jan 11, 2019
    #4
    AndrewRH likes this.