audio is working but sometimes not working

Chanhoon21 commented 5 years ago

audio is working but time goes it is not working so i need to go outside of relive and get back. it works. is there no way to work consistently

totolook commented 5 years ago

Same problem, no audio

GennadiyAMD commented 5 years ago

@chanhoon , @totolook - how does it fail? No sound at all, stutter? When it fails, does it not start at all from the beginning or quits sometime later? Could you please provide logs (c:\Program Files (x86)\Steam\logs\AMDWirelessVR.log) recorded when you're experiencing a problem?

Thanks!

Chanhoon21 commented 5 years ago

it works at all from the beginning but fail later.

here is my log

2019-08-03 01:29:15.116 2019-08-03 01:29:15.315 2019-08-03 01:29:15.315 2019-08-03 01:29:15.315 2019-08-03 01:29:15.320 2019-08-03 01:29:15.321 2019-08-03 01:29:15.321 2019-08-03 01:29:15.321 2019-08-03 01:29:15.321 2019-08-03 01:29:15.322 2019-08-03 01:29:15.322 2019-08-03 01:29:15.323 2019-08-03 01:29:15.323 2019-08-03 01:29:15.323 2019-08-03 01:29:15.323 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:15.324 2019-08-03 01:29:16.552 2019-08-03 01:29:16.552 2019-08-03 01:29:16.624 2019-08-03 01:29:17.937 2019-08-03 01:29:20.889 2019-08-03 01:29:20.897 2019-08-03 01:31:38.310 2019-08-03 01:31:38.310 2019-08-03 01:31:38.311 2019-08-03 01:31:38.592 2019-08-03 01:31:38.592 2019-08-03 01:31:38.592 2019-08-03 01:31:38.592 2019-08-03 01:31:38.592 2019-08-03 01:31:38.592 2019-08-03 01:31:38.593 Codec: hevc Render Resolution: 1440x1440 Encode Resolution: 1440x1440@72fps IPD: 0.064 Aspect: 1 Bitrate: 50000000 bps Stereo: yes SeparateEyeProcessing: yes Received from HMD: yes 6408 [VideoPipeline] Info: Ellesmere GPU detected 6408 [AWVRServerImpl] Info: StartService() Network services started. url=UDP://:1235 6408 [NetworkServer] Info: Server started, listening for incoming connections on UDP://:1235 4C40 [Comm::Server] Info: Starting server... 6408 [IServerTrackedDeviceProviderImpl] Info: Init() - OpenVR setting set 6408 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/hmd' initialized. 6408 [DisplayDriver] Info: Display '/hmd' connected. 6408 [DeviceDriver] Info: Activate(1), DeviceID=/hmd 6408 [DeviceDriver] Info: Activate(1), DeviceID=/hmd, resource root set to C:/Program Files/AMD/WVR/OpenVR/bin/win64/../../resources 6408 [DeviceDriver] Info: ReadCorrectionFromChaperoneSetup() id=10 found 6408 [DisplayDriver] Info: DeviceID = /hmd 6408 [DeviceDriver] Info: Activate(0), DeviceID=/hmd 6408 [DeviceDriver] Info: Activate(0), DeviceID=/hmd, resource root set to C:/Program Files/AMD/WVR/OpenVR/bin/win64/../../resources 6408 [DeviceDriver] Info: ReadCorrectionFromChaperoneSetup() id=10 found 6408 [DisplayDriver] Info: DeviceID = /hmd 6408 [DisplayDriver] Info: GetWindowBounds(0, 0, 2880, 1440) 6408 [DisplayDriver] Info: IsDisplayOnDesktop() = false 6408 [DisplayDriver] Info: IsDisplayRealDisplay() = false 6408 [DisplayDriver] Info: GetRecommendedRenderTargetSize(1440, 1440) 6408 [DisplayDriver] Info: GetProjectionRaw(0, -1.191754, 1.191754, -1.191754, 1.191754) 6408 [DisplayDriver] Info: GetEyeOutputViewport(0, 0, 0, 1440, 1440) 6408 [DisplayDriver] Info: GetProjectionRaw(1, -1.191754, 1.191754, -1.191754, 1.191754) 6408 [DisplayDriver] Info: GetEyeOutputViewport(1, 1440, 0, 1440, 1440) 4A9C [DisplayDriver] Info: CreateSwapTextureSet() succeded process=4216 size=1440x1440 format=29 sampleCount=1 id=1 4A9C [DisplayDriver] Info: CreateSwapTextureSet() succeded process=4216 size=1440x1440 format=29 sampleCount=1 id=2 6408 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=15812 67A4 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=25640 46C4 [DisplayDriver] Info: CreateSwapTextureSet() succeded process=15812 size=1440x1440 format=29 sampleCount=1 id=3 46C4 [DisplayDriver] Info: CreateSwapTextureSet() succeded process=15812 size=1440x1440 format=29 sampleCount=1 id=4 4C40 [AWVRServerImpl] Info: UDPServer::OnCreateSession: Discovery session created 4C40 [Comm::DatagramServer] Info: New session created 4C40 [WVRServerSession] Info: HelloRequest command received from 192.168.0.17:43275 4C40 [AWVRServerImpl] Info: UDPServer::OnCreateSession: UDP session created 4C40 [NetworkServer] Info: Client connection request received 4C40 [NetworkServer] Info: Client connection request accepted 4C40 [Comm::DatagramServer] Info: New session created 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/hmd' already exists. 4C40 [DisplayDriver] Info: Display '/hmd' connected. 4C40 [VideoPipeline] Info: Initialize(): Display: AMD WVR GearVR

2019-08-03 01:31:38.593 4C40 [VideoPipeline] Info: InitializeConverter() 2019-08-03 01:31:38.611 4C40 [VideoPipeline] Info: InitializeEncoder() start 2019-08-03 01:31:38.627 4C40 [VideoPipeline] Info: InitializeEncoder(HEVC) succeeded 2019-08-03 01:31:38.627 4C40 [NetworkServer] Info: Sent video extra data 2019-08-03 01:31:38.627 4C40 [NetworkServer] Info: Sent StartSensor 2019-08-03 01:31:38.628 4C40 [VideoPipeline] Info: InitializeEncoder() start 2019-08-03 01:31:38.634 4C40 [VideoPipeline] Info: InitializeEncoder(HEVC) succeeded 2019-08-03 01:31:38.634 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:31:38.634 4C40 [AMFAudioCaptureImpl] Info: Init() 2019-08-03 01:31:38.871 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 0, Queried 0 2019-08-03 01:31:38.873 4C40 [NetworkServer] Info: Sent audio extra data 2019-08-03 01:31:38.873 4C40 [AudioPipeline] Info: Start() start 2019-08-03 01:31:38.874 4C40 [AudioPipeline] Info: Start() - end 2019-08-03 01:31:38.874 4C40 [NetworkServer] Info: OnServiceStart - Success 2019-08-03 01:31:38.874 4C40 [NetworkServer] Info: OnServiceMessage - SERVICE_OP_CODE_START 2019-08-03 01:31:38.874 4C40 [ControllerImpl] Info: Controller: ID = /ctrlRight 2019-08-03 01:31:38.874 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/a 2019-08-03 01:31:38.874 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/b 2019-08-03 01:31:38.874 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/tr 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/grip 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/js 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Output Control: = /ctrlRight/out/haptic 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info:

2019-08-03 01:31:38.875 4C40 [OculusControllerDriver] Info: Controller device /ctrlRight created 2019-08-03 01:31:38.875 4C40 [IServerTrackedDeviceProviderImpl] Info: Added Oculus Touch controller driver 2019-08-03 01:31:38.875 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/ctrlRight' initialized. 2019-08-03 01:31:38.875 4C40 [OculusControllerDriver] Info: Controller '/ctrlRight' successfully connected as Oculus Touch. 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Controller: ID = /ctrlLeft 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/x 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/y 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/sys 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/tr 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/grip 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/js 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info: Output Control: = /ctrlLeft/out/haptic 2019-08-03 01:31:38.875 4C40 [ControllerImpl] Info:

2019-08-03 01:31:38.876 4C40 [OculusControllerDriver] Info: Controller device /ctrlLeft created 2019-08-03 01:31:38.876 4C40 [IServerTrackedDeviceProviderImpl] Info: Added Oculus Touch controller driver 2019-08-03 01:31:38.876 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/ctrlLeft' initialized. 2019-08-03 01:31:38.876 4C40 [OculusControllerDriver] Info: Controller '/ctrlLeft' successfully connected as Oculus Touch. 2019-08-03 01:31:38.876 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.78 2019-08-03 01:31:38.876 4FC0 [DeviceDriver] Info: Activate(2), DeviceID=/ctrlRight 2019-08-03 01:31:38.876 4FC0 [DeviceDriver] Info: Activate(2), DeviceID=/ctrlRight, resource root set to C:/Program Files/AMD/WVR/OpenVR/bin/win64/../../resources 2019-08-03 01:31:38.877 4FC0 [OculusControllerDriver] Info: Controller /ctrlRight activated as right Oculus Touch controller 2019-08-03 01:31:38.884 4FC0 [DeviceDriver] Info: Activate(3), DeviceID=/ctrlLeft 2019-08-03 01:31:38.884 4FC0 [DeviceDriver] Info: Activate(3), DeviceID=/ctrlLeft, resource root set to C:/Program Files/AMD/WVR/OpenVR/bin/win64/../../resources 2019-08-03 01:31:38.885 4FC0 [OculusControllerDriver] Info: Controller /ctrlLeft activated as left Oculus Touch controller 2019-08-03 01:31:40.376 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 70.831566 2019-08-03 01:31:41.793 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 72.000000 2019-08-03 01:31:42.872 4C40 [NetworkServer] Info: Average latency: full 68.25(average 68.25) client 29.22 server 30.78 encoder 14.11 network 8.24 decoder 15.39, frame time 14.25 ms 2019-08-03 01:31:46.816 4C40 [NetworkServer] Info: Average latency: full 68.85(average 68.55) client 35.10 server 30.90 encoder 14.01 network 2.85 decoder 17.13, frame time 14.57 ms 2019-08-03 01:31:50.762 4C40 [NetworkServer] Info: Average latency: full 61.02(average 66.04) client 28.32 server 29.77 encoder 13.76 network 2.92 decoder 15.00, frame time 13.98 ms 2019-08-03 01:31:54.706 4C40 [NetworkServer] Info: Average latency: full 60.87(average 64.75) client 28.74 server 29.58 encoder 13.59 network 2.55 decoder 13.71, frame time 14.02 ms 2019-08-03 01:31:58.927 4C40 [NetworkServer] Info: Average latency: full 63.29(average 64.46) client 29.58 server 31.40 encoder 14.56 network 2.31 decoder 13.92, frame time 14.05 ms 2019-08-03 01:32:02.594 4C40 [NetworkServer] Info: Average latency: full 57.88(average 63.36) client 28.55 server 27.21 encoder 12.31 network 2.12 decoder 12.09, frame time 14.07 ms 2019-08-03 01:32:06.539 4C40 [NetworkServer] Info: Average latency: full 58.16(average 62.62) client 26.61 server 29.17 encoder 13.37 network 2.38 decoder 13.32, frame time 13.91 ms 2019-08-03 01:32:10.483 4C40 [NetworkServer] Info: Average latency: full 61.68(average 62.50) client 29.71 server 29.69 encoder 13.80 network 2.28 decoder 13.47, frame time 13.91 ms 2019-08-03 01:32:26.928 4C40 [NetworkServer] Info: Average latency: full 243.52(average 82.61) client 113.86 server 119.80 encoder 54.79 network 9.86 decoder 55.53, frame time 13.91 ms 2019-08-03 01:32:30.927 4C40 [NetworkServer] Info: Average latency: full 61.06(average 80.46) client 29.27 server 29.34 encoder 13.62 network 2.44 decoder 14.07, frame time 13.92 ms 2019-08-03 01:32:34.955 4C40 [NetworkServer] Info: Average latency: full 61.76(average 78.76) client 30.15 server 29.33 encoder 13.52 network 2.28 decoder 15.90, frame time 13.92 ms 2019-08-03 01:32:36.382 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 70.586571 2019-08-03 01:32:37.802 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 71.980766 2019-08-03 01:32:38.954 4C40 [NetworkServer] Info: Average latency: full 60.86(average 77.27) client 29.39 server 29.42 encoder 13.30 network 2.05 decoder 15.01, frame time 14.09 ms 2019-08-03 01:32:42.301 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.77 2019-08-03 01:32:50.954 4C40 [NetworkServer] Info: Average latency: full 177.15(average 84.95) client 83.76 server 87.34 encoder 39.83 network 6.06 decoder 39.14, frame time 13.93 ms 2019-08-03 01:33:02.954 4C40 [NetworkServer] Info: Average latency: full 178.85(average 91.66) client 84.70 server 87.72 encoder 39.99 network 6.43 decoder 40.94, frame time 13.92 ms 2019-08-03 01:33:06.954 4C40 [NetworkServer] Info: Average latency: full 58.83(average 89.47) client 27.05 server 29.45 encoder 13.47 network 2.34 decoder 13.30, frame time 13.92 ms 2019-08-03 01:33:09.677 4C40 [NetworkServer] Info: Average latency: full 42.41(average 86.53) client 20.72 server 20.07 encoder 9.21 network 1.61 decoder 9.16, frame time 13.92 ms 2019-08-03 01:33:10.955 4C40 [NetworkServer] Info: Average latency: full 18.72(average 82.54) client 8.78 server 9.22 encoder 4.16 network 0.72 decoder 4.21, frame time 13.92 ms 2019-08-03 01:33:17.566 4C40 [NetworkServer] Info: Average latency: full 98.33(average 83.42) client 45.27 server 49.23 encoder 22.27 network 3.84 decoder 21.94, frame time 13.92 ms 2019-08-03 01:33:18.955 4C40 [NetworkServer] Info: Average latency: full 19.74(average 80.06) client 8.98 server 9.98 encoder 4.51 network 0.79 decoder 4.47, frame time 13.92 ms 2019-08-03 01:33:21.509 4C40 [NetworkServer] Info: Average latency: full 39.11(average 78.02) client 18.67 server 19.05 encoder 8.78 network 1.39 decoder 8.65, frame time 13.93 ms 2019-08-03 01:33:26.959 4C40 [NetworkServer] Info: Average latency: full 80.23(average 78.12) client 37.68 server 39.71 encoder 18.31 network 2.84 decoder 17.76, frame time 13.93 ms 2019-08-03 01:33:30.953 4C40 [NetworkServer] Info: Average latency: full 59.34(average 77.27) client 28.37 server 28.94 encoder 13.18 network 2.04 decoder 13.04, frame time 13.92 ms 2019-08-03 01:33:37.287 4C40 [NetworkServer] Info: Average latency: full 95.64(average 78.07) client 45.79 server 46.56 encoder 21.34 network 3.29 decoder 21.13, frame time 13.92 ms 2019-08-03 01:33:38.953 4C40 [NetworkServer] Info: Average latency: full 23.54(average 75.80) client 10.55 server 12.05 encoder 5.50 network 0.94 decoder 5.35, frame time 13.92 ms 2019-08-03 01:33:41.231 4C40 [NetworkServer] Info: Average latency: full 34.62(average 74.15) client 16.40 server 16.82 encoder 7.63 network 1.39 decoder 7.80, frame time 13.92 ms 2019-08-03 01:33:42.953 4C40 [NetworkServer] Info: Average latency: full 25.92(average 72.29) client 12.13 server 12.74 encoder 5.89 network 1.05 decoder 5.95, frame time 14.09 ms 2019-08-03 01:33:45.176 4C40 [NetworkServer] Info: Average latency: full 34.86(average 70.91) client 17.09 server 16.51 encoder 7.66 network 1.26 decoder 7.52, frame time 13.92 ms 2019-08-03 01:33:53.064 4C40 [NetworkServer] Info: Average latency: full 123.24(average 72.78) client 59.75 server 58.92 encoder 26.94 network 4.57 decoder 29.14, frame time 13.99 ms 2019-08-03 01:33:55.333 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30779 newID=30794 2019-08-03 01:33:57.009 4C40 [NetworkServer] Info: Average latency: full 58.42(average 72.28) client 25.62 server 30.38 encoder 13.45 network 2.42 decoder 13.01, frame time 13.92 ms 2019-08-03 01:34:05.133 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.76 2019-08-03 01:34:08.843 4C40 [NetworkServer] Info: Average latency: full 176.42(average 75.75) client 81.79 server 87.97 encoder 40.20 network 6.66 decoder 38.56, frame time 13.92 ms 2019-08-03 01:34:10.954 4C40 [NetworkServer] Info: Average latency: full 32.24(average 74.35) client 15.86 server 15.39 encoder 7.11 network 0.99 decoder 6.73, frame time 13.92 ms 2019-08-03 01:34:13.949 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 70.617981 2019-08-03 01:34:14.535 4C40 [NetworkServer] Info: Average latency: full 53.64(average 73.70) client 24.91 server 26.78 encoder 12.20 network 1.95 decoder 12.76, frame time 13.96 ms 2019-08-03 01:34:15.367 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 72.000000 2019-08-03 01:34:16.758 4C40 [NetworkServer] Info: Average latency: full 34.05(average 72.50) client 15.27 server 17.54 encoder 7.53 network 1.25 decoder 6.97, frame time 14.19 ms 2019-08-03 01:34:22.982 4C40 [NetworkServer] Info: Average latency: full 93.70(average 73.12) client 44.94 server 45.53 encoder 20.58 network 3.22 decoder 19.98, frame time 14.02 ms 2019-08-03 01:34:24.647 4C40 [NetworkServer] Info: Average latency: full 24.60(average 71.74) client 11.20 server 12.51 encoder 5.76 network 0.89 decoder 5.47, frame time 14.03 ms 2019-08-03 01:34:26.980 4C40 [NetworkServer] Info: Average latency: full 34.34(average 70.70) client 15.52 server 17.40 encoder 8.06 network 1.42 decoder 7.85, frame time 13.92 ms 2019-08-03 01:34:28.592 4C40 [NetworkServer] Info: Average latency: full 25.24(average 69.47) client 12.25 server 12.08 encoder 5.50 network 0.90 decoder 5.33, frame time 13.91 ms 2019-08-03 01:34:32.537 4C40 [NetworkServer] Info: Average latency: full 59.18(average 69.20) client 27.49 server 29.49 encoder 13.66 network 2.19 decoder 13.08, frame time 13.92 ms 2019-08-03 01:34:36.480 4C40 [NetworkServer] Info: Average latency: full 61.35(average 69.00) client 28.94 server 29.63 encoder 13.64 network 2.78 decoder 13.53, frame time 13.96 ms 2019-08-03 01:34:38.982 4C40 [NetworkServer] Info: Average latency: full 38.04(average 68.22) client 18.07 server 18.23 encoder 8.39 network 1.74 decoder 8.29, frame time 13.91 ms 2019-08-03 01:34:46.980 4C40 [NetworkServer] Info: Average latency: full 121.87(average 69.53) client 57.60 server 59.39 encoder 26.82 network 4.89 decoder 26.94, frame time 14.09 ms 2019-08-03 01:34:48.314 4C40 [NetworkServer] Info: Average latency: full 18.93(average 68.33) client 8.35 server 9.90 encoder 4.50 network 0.68 decoder 4.29, frame time 13.91 ms 2019-08-03 01:35:04.091 4C40 [NetworkServer] Info: Average latency: full 245.44(average 72.45) client 116.96 server 118.19 encoder 54.10 network 10.29 decoder 53.82, frame time 14.02 ms 2019-08-03 01:35:08.036 4C40 [NetworkServer] Info: Average latency: full 61.18(average 72.19) client 29.37 server 29.44 encoder 13.63 network 2.37 decoder 13.78, frame time 13.91 ms 2019-08-03 01:35:11.980 4C40 [NetworkServer] Info: Average latency: full 58.85(average 71.89) client 27.59 server 28.89 encoder 13.20 network 2.37 decoder 13.16, frame time 13.92 ms 2019-08-03 01:35:14.980 4C40 [NetworkServer] Info: Average latency: full 46.17(average 71.33) client 22.35 server 21.99 encoder 10.01 network 1.82 decoder 11.15, frame time 13.92 ms 2019-08-03 01:35:15.924 4C40 [NetworkServer] Info: Average latency: full 14.71(average 70.13) client 6.98 server 7.11 encoder 3.34 network 0.62 decoder 3.43, frame time 13.91 ms 2019-08-03 01:35:22.980 4C40 [NetworkServer] Info: Average latency: full 105.04(average 70.86) client 48.59 server 51.93 encoder 23.37 network 4.51 decoder 23.45, frame time 13.91 ms 2019-08-03 01:35:27.757 4C40 [NetworkServer] Info: Average latency: full 81.96(average 71.08) client 41.98 server 36.83 encoder 16.46 network 3.14 decoder 20.85, frame time 14.28 ms 2019-08-03 01:35:28.089 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.75 2019-08-03 01:35:31.701 4C40 [NetworkServer] Info: Average latency: full 59.99(average 70.86) client 28.46 server 29.10 encoder 13.26 network 2.43 decoder 13.33, frame time 13.91 ms 2019-08-03 01:35:34.979 4C40 [NetworkServer] Info: Average latency: full 48.91(average 70.43) client 23.07 server 24.07 encoder 10.86 network 1.77 decoder 10.68, frame time 13.91 ms 2019-08-03 01:35:39.591 4C40 [NetworkServer] Info: Average latency: full 70.28(average 70.43) client 33.24 server 34.40 encoder 15.71 network 2.64 decoder 15.58, frame time 13.92 ms 2019-08-03 01:35:43.535 4C40 [NetworkServer] Info: Average latency: full 58.59(average 70.21) client 26.90 server 29.24 encoder 13.35 network 2.46 decoder 13.14, frame time 13.91 ms 2019-08-03 01:35:46.979 4C40 [NetworkServer] Info: Average latency: full 53.43(average 69.89) client 25.12 server 26.05 encoder 11.56 network 2.26 decoder 11.76, frame time 13.92 ms 2019-08-03 01:35:50.979 4C40 [NetworkServer] Info: Average latency: full 62.23(average 69.76) client 29.08 server 30.64 encoder 13.78 network 2.51 decoder 13.59, frame time 14.27 ms 2019-08-03 01:35:51.423 4C40 [NetworkServer] Info: Average latency: full 6.84(average 68.63) client 3.28 server 3.29 encoder 1.52 network 0.26 decoder 1.84, frame time 13.92 ms 2019-08-03 01:35:59.312 4C40 [NetworkServer] Info: Average latency: full 121.38(average 69.56) client 57.72 server 59.17 encoder 27.34 network 4.48 decoder 27.76, frame time 13.92 ms 2019-08-03 01:36:02.979 4C40 [NetworkServer] Info: Average latency: full 58.44(average 69.37) client 28.06 server 28.14 encoder 12.70 network 2.24 decoder 12.26, frame time 14.35 ms 2019-08-03 01:36:07.201 4C40 [NetworkServer] Info: Average latency: full 63.50(average 69.27) client 29.57 server 31.50 encoder 14.37 network 2.43 decoder 14.66, frame time 13.96 ms 2019-08-03 01:36:11.146 4C40 [NetworkServer] Info: Average latency: full 63.62(average 69.17) client 31.74 server 29.44 encoder 13.20 network 2.44 decoder 15.65, frame time 13.91 ms 2019-08-03 01:36:14.979 4C40 [NetworkServer] Info: Average latency: full 57.85(average 68.99) client 27.71 server 27.99 encoder 12.50 network 2.14 decoder 12.33, frame time 14.28 ms 2019-08-03 01:36:18.979 4C40 [NetworkServer] Info: Average latency: full 60.44(average 68.85) client 28.73 server 29.17 encoder 13.22 network 2.53 decoder 13.34, frame time 13.91 ms 2019-08-03 01:36:22.979 4C40 [NetworkServer] Info: Average latency: full 59.45(average 68.70) client 28.05 server 29.28 encoder 13.06 network 2.12 decoder 13.60, frame time 13.92 ms 2019-08-03 01:36:26.978 4C40 [NetworkServer] Info: Average latency: full 59.23(average 68.55) client 27.59 server 29.49 encoder 13.53 network 2.15 decoder 13.40, frame time 13.96 ms 2019-08-03 01:36:30.881 4C40 [NetworkServer] Info: Average latency: full 58.93(average 68.40) client 28.03 server 28.85 encoder 12.96 network 2.05 decoder 13.77, frame time 13.91 ms 2019-08-03 01:36:31.115 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 71.274818 2019-08-03 01:36:32.523 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 72.000000 2019-08-03 01:36:34.993 4C40 [NetworkServer] Info: Average latency: full 61.48(average 68.30) client 29.27 server 29.99 encoder 13.54 network 2.22 decoder 13.47, frame time 14.03 ms 2019-08-03 01:36:38.992 4C40 [NetworkServer] Info: Average latency: full 59.73(average 68.17) client 28.19 server 29.42 encoder 12.96 network 2.12 decoder 12.81, frame time 13.92 ms 2019-08-03 01:36:39.025 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.74 2019-08-03 01:36:41.619 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:41.619 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:41.855 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:41.856 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.126 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.127 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.373 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.373 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.650 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.651 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.715 4C40 [NetworkServer] Info: Average latency: full 61.48(average 68.07) client 30.00 server 28.23 encoder 12.61 network 3.25 decoder 12.84, frame time 13.91 ms 2019-08-03 01:36:42.886 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:42.887 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.157 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.158 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.401 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.401 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.671 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.671 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.979 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:43.984 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.297 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3227 2019-08-03 01:36:44.297 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3228 2019-08-03 01:36:44.297 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3229 2019-08-03 01:36:44.298 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3230 2019-08-03 01:36:44.298 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3231 2019-08-03 01:36:44.298 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3232 2019-08-03 01:36:44.298 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3233 2019-08-03 01:36:44.298 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3234 2019-08-03 01:36:44.299 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3235 2019-08-03 01:36:44.299 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=3225 newID=3236 2019-08-03 01:36:44.301 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.305 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.628 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.632 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.953 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:44.959 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.273 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.277 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.593 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.599 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.918 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:45.926 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.244 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.250 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.561 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.566 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.658 4C40 [NetworkServer] Info: Average latency: full 93.10(average 68.43) client 52.12 server 34.31 encoder 13.22 network 6.68 decoder 12.27, frame time 13.94 ms 2019-08-03 01:36:46.878 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.887 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:36:46.992 4C40 [NetworkServer] Info: Average latency: full 7.74(average 67.57) client 4.37 server 2.70 encoder 0.99 network 0.68 decoder 0.71, frame time 13.91 ms 2019-08-03 01:36:50.603 4C40 [NetworkServer] Info: Average latency: full 55.01(average 67.39) client 25.18 server 27.27 encoder 12.43 network 2.56 decoder 12.60, frame time 13.94 ms 2019-08-03 01:36:54.992 4C40 [NetworkServer] Info: Average latency: full 68.60(average 67.41) client 33.07 server 32.66 encoder 14.83 network 2.88 decoder 14.92, frame time 13.98 ms 2019-08-03 01:36:58.492 4C40 [NetworkServer] Info: Average latency: full 53.11(average 67.21) client 24.29 server 26.62 encoder 11.99 network 2.20 decoder 12.53, frame time 13.93 ms 2019-08-03 01:37:02.993 4C40 [NetworkServer] Info: Average latency: full 68.53(average 67.23) client 33.07 server 33.00 encoder 15.07 network 2.46 decoder 15.05, frame time 13.91 ms 2019-08-03 01:37:06.381 4C40 [NetworkServer] Info: Average latency: full 52.16(average 67.03) client 24.53 server 25.50 encoder 11.66 network 2.12 decoder 11.72, frame time 13.92 ms 2019-08-03 01:37:10.992 4C40 [NetworkServer] Info: Average latency: full 72.17(average 67.10) client 35.55 server 34.15 encoder 15.64 network 2.47 decoder 15.55, frame time 14.22 ms 2019-08-03 01:37:18.214 4C40 [NetworkServer] Info: Average latency: full 110.78(average 67.66) client 52.74 server 53.50 encoder 24.45 network 4.53 decoder 25.39, frame time 13.92 ms 2019-08-03 01:37:18.991 4C40 [NetworkServer] Info: Average latency: full 12.24(average 66.95) client 6.01 server 5.79 encoder 2.67 network 0.45 decoder 2.72, frame time 13.91 ms 2019-08-03 01:37:22.992 4C40 [NetworkServer] Info: Average latency: full 58.98(average 66.85) client 27.31 server 29.29 encoder 13.44 network 2.38 decoder 14.04, frame time 13.92 ms 2019-08-03 01:37:26.102 4C40 [NetworkServer] Info: Average latency: full 48.55(average 66.62) client 23.99 server 22.77 encoder 10.24 network 1.80 decoder 10.77, frame time 13.91 ms 2019-08-03 01:37:27.531 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=12970 newID=12985 2019-08-03 01:37:30.991 4C40 [NetworkServer] Info: Average latency: full 73.26(average 66.71) client 33.97 server 36.69 encoder 16.37 network 2.60 decoder 16.60, frame time 13.91 ms 2019-08-03 01:37:34.991 4C40 [NetworkServer] Info: Average latency: full 61.78(average 66.65) client 29.60 server 29.55 encoder 13.38 network 2.63 decoder 13.92, frame time 13.92 ms 2019-08-03 01:37:41.879 4C40 [NetworkServer] Info: Average latency: full 107.08(average 67.13) client 51.21 server 51.76 encoder 23.63 network 4.12 decoder 23.71, frame time 13.95 ms 2019-08-03 01:37:42.991 4C40 [NetworkServer] Info: Average latency: full 18.59(average 66.55) client 9.17 server 8.92 encoder 3.80 network 0.49 decoder 3.33, frame time 15.34 ms 2019-08-03 01:37:45.826 4C40 [NetworkServer] Info: Average latency: full 42.74(average 66.27) client 19.71 server 21.23 encoder 9.87 network 1.80 decoder 9.90, frame time 13.92 ms 2019-08-03 01:37:46.991 4C40 [NetworkServer] Info: Average latency: full 16.86(average 65.70) client 7.68 server 8.53 encoder 3.92 network 0.65 decoder 3.90, frame time 13.91 ms 2019-08-03 01:37:57.657 4C40 [NetworkServer] Info: Average latency: full 162.49(average 66.81) client 76.29 server 79.86 encoder 35.99 network 6.34 decoder 36.56, frame time 13.91 ms 2019-08-03 01:37:58.992 4C40 [NetworkServer] Info: Average latency: full 20.31(average 66.28) client 9.65 server 9.92 encoder 4.46 network 0.74 decoder 4.37, frame time 13.92 ms 2019-08-03 01:38:05.546 4C40 [NetworkServer] Info: Average latency: full 102.13(average 66.69) client 45.86 server 51.50 encoder 22.21 network 4.76 decoder 23.03, frame time 13.92 ms 2019-08-03 01:38:10.991 4C40 [NetworkServer] Info: Average latency: full 81.54(average 66.85) client 36.87 server 40.74 encoder 18.06 network 3.93 decoder 18.24, frame time 13.93 ms 2019-08-03 01:38:13.435 4C40 [NetworkServer] Info: Average latency: full 36.44(average 66.52) client 16.89 server 18.12 encoder 8.25 network 1.42 decoder 8.59, frame time 13.96 ms 2019-08-03 01:38:14.991 4C40 [NetworkServer] Info: Average latency: full 22.38(average 66.04) client 10.13 server 11.39 encoder 5.22 network 0.86 decoder 5.15, frame time 13.91 ms 2019-08-03 01:38:17.379 4C40 [NetworkServer] Info: Average latency: full 37.71(average 65.73) client 18.44 server 17.93 encoder 8.28 network 1.34 decoder 8.04, frame time 14.01 ms 2019-08-03 01:38:24.388 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.73 2019-08-03 01:38:34.992 4C40 [NetworkServer] Info: Average latency: full 281.61(average 68.03) client 128.69 server 136.49 encoder 58.78 network 16.43 decoder 64.64, frame time 13.92 ms 2019-08-03 01:38:38.992 4C40 [NetworkServer] Info: Average latency: full 65.89(average 68.01) client 28.07 server 31.69 encoder 13.29 network 6.13 decoder 13.98, frame time 14.06 ms 2019-08-03 01:38:48.934 4C40 [NetworkServer] Info: Average latency: full 154.53(average 68.91) client 71.88 server 75.08 encoder 33.59 network 7.57 decoder 34.52, frame time 13.93 ms 2019-08-03 01:38:52.934 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32131 2019-08-03 01:38:52.934 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32132 2019-08-03 01:38:52.934 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32133 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32134 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32135 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32136 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32137 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32138 2019-08-03 01:38:52.935 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32139 2019-08-03 01:38:52.936 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=32129 newID=32140 2019-08-03 01:38:52.936 4C40 [NetworkServer] Info: Average latency: full 65.92(average 68.88) client 29.41 server 31.01 encoder 13.29 network 5.49 decoder 14.72, frame time 13.92 ms 2019-08-03 01:38:56.823 4C40 [NetworkServer] Info: Average latency: full 69.29(average 68.88) client 29.68 server 33.62 encoder 13.36 network 6.00 decoder 13.92, frame time 13.91 ms 2019-08-03 01:38:59.859 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=33691 newID=33707 2019-08-03 01:39:04.712 4C40 [NetworkServer] Info: Average latency: full 125.61(average 69.45) client 59.13 server 61.32 encoder 26.83 network 5.15 decoder 26.75, frame time 14.09 ms 2019-08-03 01:39:08.656 4C40 [NetworkServer] Info: Average latency: full 58.71(average 69.35) client 27.07 server 29.26 encoder 13.20 network 2.38 decoder 13.76, frame time 13.92 ms 2019-08-03 01:39:10.990 4C40 [NetworkServer] Info: Average latency: full 36.49(average 69.02) client 17.87 server 17.18 encoder 7.93 network 1.44 decoder 7.92, frame time 13.92 ms 2019-08-03 01:39:14.989 4C40 [NetworkServer] Info: Average latency: full 59.87(average 68.93) client 27.78 server 29.41 encoder 13.49 network 2.67 decoder 14.47, frame time 13.91 ms 2019-08-03 01:39:16.545 4C40 [NetworkServer] Info: Average latency: full 23.19(average 68.49) client 10.80 server 11.42 encoder 5.10 network 0.97 decoder 5.31, frame time 13.92 ms 2019-08-03 01:39:26.989 4C40 [NetworkServer] Info: Average latency: full 157.58(average 69.34) client 74.92 server 76.41 encoder 34.88 network 6.25 decoder 36.53, frame time 13.93 ms 2019-08-03 01:39:28.378 4C40 [NetworkServer] Info: Average latency: full 22.11(average 68.89) client 10.91 server 10.49 encoder 4.71 network 0.70 decoder 4.65, frame time 13.91 ms 2019-08-03 01:39:30.989 4C40 [NetworkServer] Info: Average latency: full 39.13(average 68.61) client 18.54 server 19.08 encoder 8.78 network 1.51 decoder 8.76, frame time 13.91 ms 2019-08-03 01:39:32.323 4C40 [NetworkServer] Info: Average latency: full 18.92(average 68.15) client 8.35 server 9.84 encoder 4.51 network 0.72 decoder 4.42, frame time 13.92 ms 2019-08-03 01:39:36.267 4C40 [NetworkServer] Info: Average latency: full 61.42(average 68.09) client 29.72 server 29.36 encoder 13.38 network 2.34 decoder 13.88, frame time 13.92 ms 2019-08-03 01:39:38.989 4C40 [NetworkServer] Info: Average latency: full 43.57(average 67.86) client 20.78 server 20.80 encoder 9.35 network 2.00 decoder 9.32, frame time 14.45 ms 2019-08-03 01:39:42.989 4C40 [NetworkServer] Info: Average latency: full 60.94(average 67.80) client 28.79 server 29.60 encoder 13.66 network 2.55 decoder 14.15, frame time 13.91 ms 2019-08-03 01:39:44.156 4C40 [NetworkServer] Info: Average latency: full 18.00(average 67.35) client 8.13 server 8.89 encoder 3.89 network 0.97 decoder 4.33, frame time 13.91 ms 2019-08-03 01:39:46.990 4C40 [NetworkServer] Info: Average latency: full 42.43(average 67.13) client 20.50 server 20.21 encoder 9.01 network 1.73 decoder 9.64, frame time 13.92 ms 2019-08-03 01:39:48.101 4C40 [NetworkServer] Info: Average latency: full 17.31(average 66.69) client 8.57 server 8.07 encoder 3.66 network 0.67 decoder 3.85, frame time 13.92 ms 2019-08-03 01:39:54.833 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.72 2019-08-03 01:40:03.877 4C40 [NetworkServer] Info: Average latency: full 241.75(average 68.22) client 113.59 server 119.09 encoder 54.46 network 9.07 decoder 53.89, frame time 14.02 ms 2019-08-03 01:40:07.822 4C40 [NetworkServer] Info: Average latency: full 61.40(average 68.16) client 28.86 server 30.26 encoder 13.63 network 2.28 decoder 12.78, frame time 14.28 ms 2019-08-03 01:40:11.766 4C40 [NetworkServer] Info: Average latency: full 63.10(average 68.12) client 30.89 server 29.97 encoder 13.74 network 2.25 decoder 13.63, frame time 14.27 ms 2019-08-03 01:40:15.012 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=50558 newID=50576 2019-08-03 01:40:15.711 4C40 [NetworkServer] Info: Average latency: full 11.78(average 67.64) client 4.81 server 6.49 encoder 2.52 network 0.49 decoder 2.44, frame time 13.92 ms 2019-08-03 01:40:22.988 4C40 [NetworkServer] Info: Average latency: full 109.34(average 67.99) client 51.38 server 53.75 encoder 24.49 network 4.20 decoder 24.87, frame time 13.91 ms 2019-08-03 01:40:23.601 4C40 [NetworkServer] Info: Average latency: full 8.49(average 67.49) client 3.75 server 4.41 encoder 2.01 network 0.33 decoder 1.96, frame time 13.91 ms 2019-08-03 01:40:26.342 4C40 [NetworkServer] Info: StopStreaming() start 2019-08-03 01:40:26.342 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/ctrlRight) 2019-08-03 01:40:26.342 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/ctrlLeft) 2019-08-03 01:40:26.342 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/hmd) 2019-08-03 01:40:26.342 4C40 [DisplayDriver] Info: Display '/hmd' disconnected. 2019-08-03 01:40:26.342 4C40 [VideoPipeline] Info: Terminate()... 2019-08-03 01:40:26.342 4C40 [VideoPipeline] Info: Waiting for the poller thread to stop... 2019-08-03 01:40:26.346 4C40 [VideoPipeline] Info: Shutting down encoder... 2019-08-03 01:40:26.352 4C40 [VideoPipeline] Info: Shutting down CSC... 2019-08-03 01:40:26.355 4C40 [AudioPipeline] Info: Terminate() start 2019-08-03 01:40:26.377 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:40:26.381 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:40:26.381 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 52742, Queried 52742 2019-08-03 01:40:26.381 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 0, Queried 0 2019-08-03 01:40:26.381 4C40 [AMFAudioEncoderFFMPEGImpl] Info: Submitted 52742, Queried 22713 2019-08-03 01:40:26.382 4C40 [AudioPipeline] Info: Terminate() end 2019-08-03 01:40:26.382 4C40 [NetworkServer] Info: Sent StopSensor 2019-08-03 01:40:26.382 4C40 [NetworkServer] Info: OnServiceStop: Client connection terminated, reason: stop command 2019-08-03 01:40:26.382 4C40 [NetworkServer] Info: OnServiceMessage - SERVICE_OP_CODE_STOP 2019-08-03 01:40:27.887 4C40 [AWVRServerImpl] Info: UDPServer::OnCreateSession: Discovery session created 2019-08-03 01:40:27.887 4C40 [Comm::DatagramServer] Info: New session created 2019-08-03 01:40:27.887 4C40 [WVRServerSession] Info: HelloRequest command received from 192.168.0.17:46746 2019-08-03 01:40:28.143 4C40 [AWVRServerImpl] Info: UDPServer::OnCreateSession: UDP session created 2019-08-03 01:40:28.143 4C40 [NetworkServer] Info: Client connection request received 2019-08-03 01:40:28.143 4C40 [NetworkServer] Info: Client connection request accepted 2019-08-03 01:40:28.143 4C40 [Comm::DatagramServer] Info: New session created 2019-08-03 01:40:28.143 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/hmd' already exists. 2019-08-03 01:40:28.143 4C40 [DisplayDriver] Info: Display '/hmd' connected. 2019-08-03 01:40:28.143 4C40 [VideoPipeline] Info: Initialize(): Display: AMD WVR GearVR Codec: hevc Render Resolution: 1440x1440 Encode Resolution: 1440x1440@72fps IPD: 0.064 Aspect: 1 Bitrate: 50000000 bps Stereo: yes SeparateEyeProcessing: yes Received from HMD: yes

2019-08-03 01:40:28.143 4C40 [VideoPipeline] Info: InitializeConverter() 2019-08-03 01:40:28.146 4C40 [VideoPipeline] Info: InitializeEncoder() start 2019-08-03 01:40:28.160 4C40 [VideoPipeline] Info: InitializeEncoder(HEVC) succeeded 2019-08-03 01:40:28.160 4C40 [NetworkServer] Info: Sent video extra data 2019-08-03 01:40:28.160 4C40 [NetworkServer] Info: Sent StartSensor 2019-08-03 01:40:28.161 4C40 [VideoPipeline] Info: InitializeEncoder() start 2019-08-03 01:40:28.165 4C40 [VideoPipeline] Info: InitializeEncoder(HEVC) succeeded 2019-08-03 01:40:28.165 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:40:28.165 4C40 [AMFAudioCaptureImpl] Info: Init() 2019-08-03 01:40:28.226 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 0, Queried 0 2019-08-03 01:40:28.229 4C40 [NetworkServer] Info: Sent audio extra data 2019-08-03 01:40:28.229 4C40 [AudioPipeline] Info: Start() start 2019-08-03 01:40:28.247 4C40 [AudioPipeline] Info: Start() - end 2019-08-03 01:40:28.247 4C40 [NetworkServer] Info: OnServiceStart - Success 2019-08-03 01:40:28.247 4C40 [NetworkServer] Info: OnServiceMessage - SERVICE_OP_CODE_START 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Controller: ID = /ctrlRight 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/a 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/b 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/tr 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/grip 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlRight/in/js 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Output Control: = /ctrlRight/out/haptic 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info:

2019-08-03 01:40:28.247 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/ctrlRight' already exists. 2019-08-03 01:40:28.247 4C40 [OculusControllerDriver] Info: Controller '/ctrlRight' successfully connected as Oculus Touch. 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Controller: ID = /ctrlLeft 2019-08-03 01:40:28.247 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/x 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/y 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/sys 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/tr 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/grip 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Input Control: = /ctrlLeft/in/js 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info: Output Control: = /ctrlLeft/out/haptic 2019-08-03 01:40:28.248 4C40 [ControllerImpl] Info:

2019-08-03 01:40:28.248 4C40 [IServerTrackedDeviceProviderImpl] Info: CreateDeviceDriver(): Device '/ctrlLeft' already exists. 2019-08-03 01:40:28.248 4C40 [OculusControllerDriver] Info: Controller '/ctrlLeft' successfully connected as Oculus Touch. 2019-08-03 01:40:28.248 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.72 2019-08-03 01:40:28.248 4C40 [DeviceDriver] Info: Device (/ctrlRight) battery level is 0.20 2019-08-03 01:40:28.248 4C40 [DeviceDriver] Info: Device (/ctrlLeft) battery level is 0.10 2019-08-03 01:40:32.321 4C40 [NetworkServer] Info: Average latency: full 60.07(average 60.07) client 27.98 server 29.57 encoder 13.50 network 2.51 decoder 15.21, frame time 17.83 ms 2019-08-03 01:40:36.265 4C40 [NetworkServer] Info: Average latency: full 60.53(average 60.30) client 28.52 server 29.51 encoder 13.35 network 2.50 decoder 13.44, frame time 13.92 ms 2019-08-03 01:40:40.210 4C40 [NetworkServer] Info: Average latency: full 59.08(average 59.89) client 27.03 server 29.59 encoder 13.25 network 2.46 decoder 13.64, frame time 13.94 ms 2019-08-03 01:40:44.378 4C40 [NetworkServer] Info: Average latency: full 61.76(average 60.36) client 28.53 server 30.75 encoder 14.20 network 2.49 decoder 14.21, frame time 13.94 ms 2019-08-03 01:40:48.101 4C40 [NetworkServer] Info: Average latency: full 57.43(average 59.77) client 27.43 server 27.74 encoder 12.75 network 2.27 decoder 12.92, frame time 13.98 ms 2019-08-03 01:40:52.378 4C40 [NetworkServer] Info: Average latency: full 62.97(average 60.31) client 29.58 server 31.14 encoder 14.25 network 2.25 decoder 13.84, frame time 13.91 ms 2019-08-03 01:40:56.378 4C40 [NetworkServer] Info: Average latency: full 58.42(average 60.04) client 26.93 server 29.30 encoder 13.32 network 2.19 decoder 13.23, frame time 13.95 ms 2019-08-03 01:40:57.657 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=6611 newID=6624 2019-08-03 01:41:00.377 4C40 [NetworkServer] Info: Average latency: full 59.60(average 59.98) client 26.91 server 30.34 encoder 13.73 network 2.36 decoder 13.92, frame time 13.92 ms 2019-08-03 01:41:05.180 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.71 2019-08-03 01:41:07.821 4C40 [NetworkServer] Info: Average latency: full 113.76(average 65.96) client 53.47 server 55.71 encoder 25.18 network 4.58 decoder 25.89, frame time 13.96 ms 2019-08-03 01:41:16.377 4C40 [NetworkServer] Info: Average latency: full 130.32(average 72.39) client 60.40 server 63.93 encoder 28.65 network 5.99 decoder 29.81, frame time 13.92 ms 2019-08-03 01:41:20.375 4C40 [NetworkServer] Info: Average latency: full 58.95(average 71.17) client 27.58 server 29.02 encoder 12.94 network 2.35 decoder 13.74, frame time 13.92 ms 2019-08-03 01:41:24.377 4C40 [NetworkServer] Info: Average latency: full 60.91(average 70.32) client 29.04 server 29.58 encoder 13.70 network 2.29 decoder 13.95, frame time 13.94 ms 2019-08-03 01:41:27.543 4C40 [NetworkServer] Info: Average latency: full 47.98(average 68.60) client 22.45 server 23.57 encoder 10.93 network 1.96 decoder 11.38, frame time 13.91 ms 2019-08-03 01:41:28.376 4C40 [NetworkServer] Info: Average latency: full 12.21(average 64.57) client 5.55 server 6.15 encoder 2.82 network 0.51 decoder 3.04, frame time 13.92 ms 2019-08-03 01:41:31.488 4C40 [NetworkServer] Info: Average latency: full 48.34(average 63.49) client 23.42 server 23.05 encoder 10.67 network 1.87 decoder 10.75, frame time 14.04 ms 2019-08-03 01:41:32.377 4C40 [NetworkServer] Info: Average latency: full 13.54(average 60.37) client 6.47 server 6.57 encoder 3.05 network 0.51 decoder 2.99, frame time 13.93 ms 2019-08-03 01:41:35.433 4C40 [NetworkServer] Info: Average latency: full 47.04(average 59.58) client 22.09 server 23.24 encoder 10.69 network 1.71 decoder 10.19, frame time 14.09 ms 2019-08-03 01:41:36.376 4C40 [NetworkServer] Info: Average latency: full 13.37(average 57.02) client 6.05 server 6.94 encoder 3.24 network 0.39 decoder 3.12, frame time 13.92 ms 2019-08-03 01:41:43.321 4C40 [NetworkServer] Info: Average latency: full 106.55(average 59.62) client 50.50 server 51.84 encoder 23.74 network 4.21 decoder 24.10, frame time 13.95 ms 2019-08-03 01:41:44.375 4C40 [NetworkServer] Info: Average latency: full 16.84(average 57.48) client 8.32 server 7.83 encoder 3.56 network 0.69 decoder 3.66, frame time 13.92 ms 2019-08-03 01:41:47.264 4C40 [NetworkServer] Info: Average latency: full 44.47(average 56.86) client 20.92 server 21.45 encoder 9.83 network 2.09 decoder 10.71, frame time 13.91 ms 2019-08-03 01:41:51.209 4C40 [NetworkServer] Info: Average latency: full 62.06(average 57.10) client 29.63 server 29.24 encoder 13.51 network 3.19 decoder 15.02, frame time 13.93 ms 2019-08-03 01:41:55.155 4C40 [NetworkServer] Info: Average latency: full 65.90(average 57.48) client 31.81 server 29.88 encoder 13.65 network 4.20 decoder 16.06, frame time 14.11 ms 2019-08-03 01:41:59.099 4C40 [NetworkServer] Info: Average latency: full 64.26(average 57.77) client 30.84 server 29.49 encoder 13.27 network 3.93 decoder 16.32, frame time 13.91 ms 2019-08-03 01:42:03.044 4C40 [NetworkServer] Info: Average latency: full 65.37(average 58.07) client 31.17 server 30.58 encoder 14.05 network 3.61 decoder 15.87, frame time 14.30 ms 2019-08-03 01:42:04.376 4C40 [NetworkServer] Info: Average latency: full 21.03(average 56.65) client 9.69 server 9.94 encoder 4.44 network 1.40 decoder 5.43, frame time 13.91 ms 2019-08-03 01:42:06.558 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:06.559 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:06.835 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:06.836 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:06.987 4C40 [NetworkServer] Info: Average latency: full 44.99(average 56.21) client 22.51 server 19.71 encoder 9.01 network 2.77 decoder 10.65, frame time 13.92 ms 2019-08-03 01:42:07.069 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.073 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.344 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.344 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.651 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.651 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.894 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:07.894 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.158 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.164 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.404 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.406 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.675 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.675 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=22613 newID=22630 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22614 dist=-16 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22615 dist=-15 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22616 dist=-14 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22617 dist=-13 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22618 dist=-12 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22619 dist=-11 2019-08-03 01:42:08.985 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22620 dist=-10 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22621 dist=-9 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22622 dist=-8 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22623 dist=-7 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22624 dist=-6 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22625 dist=-5 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22626 dist=-4 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22627 dist=-3 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22628 dist=-2 2019-08-03 01:42:08.986 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22629 dist=-1 2019-08-03 01:42:08.988 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:08.992 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=22693 newID=22705 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22694 dist=-11 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22695 dist=-10 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22696 dist=-9 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22697 dist=-8 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22698 dist=-7 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22699 dist=-6 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22700 dist=-5 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22701 dist=-4 2019-08-03 01:42:09.307 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22702 dist=-3 2019-08-03 01:42:09.308 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22703 dist=-2 2019-08-03 01:42:09.308 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22704 dist=-1 2019-08-03 01:42:09.309 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.312 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.627 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=22761 newID=22781 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22762 dist=-19 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22763 dist=-18 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22764 dist=-17 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22765 dist=-16 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22766 dist=-15 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22767 dist=-14 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22768 dist=-13 2019-08-03 01:42:09.628 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22769 dist=-12 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22770 dist=-14 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22771 dist=-13 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22772 dist=-12 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22773 dist=-11 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22774 dist=-10 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22775 dist=-9 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22776 dist=-8 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22777 dist=-7 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22778 dist=-6 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22779 dist=-5 2019-08-03 01:42:09.629 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22780 dist=-4 2019-08-03 01:42:09.630 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.633 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.958 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:09.968 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.268 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=22921 newID=22925 2019-08-03 01:42:10.268 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=22921 newID=22926 2019-08-03 01:42:10.268 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22922 dist=-4 2019-08-03 01:42:10.269 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22923 dist=-3 2019-08-03 01:42:10.269 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=22924 dist=-2 2019-08-03 01:42:10.270 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.272 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.589 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.593 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.903 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23056 newID=23060 2019-08-03 01:42:10.903 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23056 newID=23061 2019-08-03 01:42:10.903 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23057 dist=-4 2019-08-03 01:42:10.903 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23058 dist=-3 2019-08-03 01:42:10.903 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23059 dist=-2 2019-08-03 01:42:10.906 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.913 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:10.932 4C40 [NetworkServer] Info: Average latency: full 88.33(average 57.36) client 49.22 server 34.90 encoder 13.08 network 4.21 decoder 11.94, frame time 13.91 ms 2019-08-03 01:42:11.222 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23141 newID=23146 2019-08-03 01:42:11.222 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23146 newID=23153 2019-08-03 01:42:11.222 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23142 dist=-11 2019-08-03 01:42:11.222 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23143 dist=-10 2019-08-03 01:42:11.222 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23144 dist=-9 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23145 dist=-8 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23147 dist=-6 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23148 dist=-5 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23149 dist=-4 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23150 dist=-3 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23151 dist=-2 2019-08-03 01:42:11.223 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23152 dist=-1 2019-08-03 01:42:11.224 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:11.233 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23205 newID=23207 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23207 newID=23211 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23207 newID=23212 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23207 newID=23213 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23207 newID=23214 2019-08-03 01:42:11.540 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23207 newID=23215 2019-08-03 01:42:11.541 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23220 2019-08-03 01:42:11.541 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23221 2019-08-03 01:42:11.541 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23222 2019-08-03 01:42:11.541 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23223 2019-08-03 01:42:11.541 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23224 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=23215 newID=23225 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23206 dist=-19 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23208 dist=-17 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23209 dist=-16 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23210 dist=-15 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23216 dist=-9 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23217 dist=-8 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23218 dist=-7 2019-08-03 01:42:11.542 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=23219 dist=-6 2019-08-03 01:42:11.553 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:11.558 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:11.860 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:11.864 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:42:14.876 4C40 [NetworkServer] Info: Average latency: full 76.10(average 58.01) client 41.12 server 30.84 encoder 13.12 network 4.14 decoder 15.70, frame time 14.28 ms 2019-08-03 01:42:16.375 4C40 [NetworkServer] Info: Average latency: full 23.17(average 56.85) client 10.87 server 10.88 encoder 5.02 network 1.42 decoder 6.26, frame time 13.91 ms 2019-08-03 01:42:18.819 4C40 [NetworkServer] Info: Average latency: full 39.80(average 56.30) client 18.78 server 18.26 encoder 8.19 network 2.76 decoder 10.06, frame time 13.91 ms 2019-08-03 01:42:20.056 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.70 2019-08-03 01:42:22.764 4C40 [NetworkServer] Info: Average latency: full 64.64(average 56.56) client 30.40 server 29.97 encoder 13.43 network 4.27 decoder 15.61, frame time 13.95 ms 2019-08-03 01:42:28.375 4C40 [NetworkServer] Info: Average latency: full 88.70(average 57.53) client 42.28 server 41.21 encoder 19.15 network 5.22 decoder 21.76, frame time 14.03 ms 2019-08-03 01:42:34.598 4C40 [NetworkServer] Info: Average latency: full 102.74(average 58.86) client 48.86 server 48.44 encoder 22.13 network 5.44 decoder 23.75, frame time 14.30 ms 2019-08-03 01:42:38.542 4C40 [NetworkServer] Info: Average latency: full 60.34(average 58.90) client 28.19 server 30.26 encoder 13.51 network 1.89 decoder 12.82, frame time 14.29 ms 2019-08-03 01:42:40.375 4C40 [NetworkServer] Info: Average latency: full 29.95(average 58.10) client 14.85 server 14.06 encoder 6.06 network 1.04 decoder 5.86, frame time 14.71 ms 2019-08-03 01:42:42.487 4C40 [NetworkServer] Info: Average latency: full 31.87(average 57.39) client 14.33 server 15.97 encoder 7.17 network 1.57 decoder 7.74, frame time 13.92 ms 2019-08-03 01:42:56.375 4C40 [NetworkServer] Info: Average latency: full 212.80(average 61.48) client 100.48 server 102.51 encoder 47.29 network 9.81 decoder 50.68, frame time 13.94 ms 2019-08-03 01:42:58.263 4C40 [NetworkServer] Info: Average latency: full 28.04(average 60.62) client 12.80 server 14.00 encoder 6.32 network 1.23 decoder 6.64, frame time 13.93 ms 2019-08-03 01:43:00.375 4C40 [NetworkServer] Info: Average latency: full 33.24(average 59.94) client 16.46 server 15.30 encoder 6.83 network 1.48 decoder 7.72, frame time 13.93 ms 2019-08-03 01:43:02.209 4C40 [NetworkServer] Info: Average latency: full 28.43(average 59.17) client 13.22 server 13.80 encoder 6.42 network 1.41 decoder 6.43, frame time 13.91 ms 2019-08-03 01:43:04.375 4C40 [NetworkServer] Info: Average latency: full 32.42(average 58.53) client 15.02 server 15.93 encoder 7.29 network 1.46 decoder 8.63, frame time 13.92 ms 2019-08-03 01:43:06.152 4C40 [NetworkServer] Info: Average latency: full 28.50(average 57.83) client 13.98 server 13.09 encoder 6.03 network 1.44 decoder 6.56, frame time 13.92 ms 2019-08-03 01:43:10.097 4C40 [NetworkServer] Info: Average latency: full 61.88(average 57.93) client 29.21 server 29.53 encoder 13.57 network 3.13 decoder 14.76, frame time 13.92 ms 2019-08-03 01:43:12.375 4C40 [NetworkServer] Info: Average latency: full 34.87(average 57.41) client 16.18 server 16.75 encoder 7.63 network 1.94 decoder 8.13, frame time 13.95 ms 2019-08-03 01:43:16.374 4C40 [NetworkServer] Info: Average latency: full 60.09(average 57.47) client 27.84 server 29.20 encoder 13.36 network 3.05 decoder 14.09, frame time 13.92 ms 2019-08-03 01:43:17.986 4C40 [NetworkServer] Info: Average latency: full 26.17(average 56.80) client 12.93 server 12.01 encoder 5.64 network 1.23 decoder 5.98, frame time 13.92 ms 2019-08-03 01:43:24.376 4C40 [NetworkServer] Info: Average latency: full 96.55(average 57.63) client 45.05 server 46.68 encoder 21.26 network 4.81 decoder 22.89, frame time 13.92 ms 2019-08-03 01:43:25.875 4C40 [NetworkServer] Info: Average latency: full 23.77(average 56.94) client 11.65 server 11.19 encoder 5.16 network 0.93 decoder 5.18, frame time 13.92 ms 2019-08-03 01:43:36.374 4C40 [NetworkServer] Info: Average latency: full 158.71(average 58.98) client 73.81 server 77.53 encoder 35.77 network 7.38 decoder 37.61, frame time 13.94 ms 2019-08-03 01:43:41.654 4C40 [NetworkServer] Info: Average latency: full 83.09(average 59.45) client 40.04 server 39.60 encoder 18.36 network 3.45 decoder 20.22, frame time 13.92 ms 2019-08-03 01:43:45.596 4C40 [NetworkServer] Info: Average latency: full 61.10(average 59.48) client 28.57 server 29.72 encoder 13.36 network 2.81 decoder 14.36, frame time 13.91 ms 2019-08-03 01:43:49.540 4C40 [NetworkServer] Info: Average latency: full 61.06(average 59.51) client 29.09 server 29.29 encoder 13.49 network 2.68 decoder 14.49, frame time 13.91 ms 2019-08-03 01:43:57.429 4C40 [NetworkServer] Info: Average latency: full 121.26(average 60.66) client 57.49 server 58.54 encoder 27.00 network 5.24 decoder 27.96, frame time 13.92 ms 2019-08-03 01:44:05.318 4C40 [NetworkServer] Info: Average latency: full 127.37(average 61.87) client 61.29 server 60.58 encoder 27.55 network 5.49 decoder 27.68, frame time 14.27 ms 2019-08-03 01:44:09.263 4C40 [NetworkServer] Info: Average latency: full 62.00(average 61.87) client 28.81 server 30.37 encoder 13.41 network 2.82 decoder 14.54, frame time 13.91 ms 2019-08-03 01:44:17.152 4C40 [NetworkServer] Info: Average latency: full 125.91(average 62.99) client 61.47 server 59.01 encoder 26.98 network 5.43 decoder 32.09, frame time 14.02 ms 2019-08-03 01:44:20.374 4C40 [NetworkServer] Info: Average latency: full 51.01(average 62.79) client 25.38 server 23.48 encoder 10.72 network 2.14 decoder 11.69, frame time 13.92 ms 2019-08-03 01:44:24.373 4C40 [NetworkServer] Info: Average latency: full 59.22(average 62.73) client 27.40 server 29.18 encoder 13.23 network 2.64 decoder 14.36, frame time 13.91 ms 2019-08-03 01:44:26.335 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.69 2019-08-03 01:44:28.374 4C40 [NetworkServer] Info: Average latency: full 61.71(average 62.71) client 29.26 server 29.82 encoder 13.58 network 2.62 decoder 13.80, frame time 13.93 ms 2019-08-03 01:44:32.373 4C40 [NetworkServer] Info: Average latency: full 60.14(average 62.67) client 28.61 server 29.03 encoder 13.31 network 2.50 decoder 14.16, frame time 13.92 ms 2019-08-03 01:44:36.372 4C40 [NetworkServer] Info: Average latency: full 60.24(average 62.63) client 28.15 server 29.52 encoder 13.69 network 2.56 decoder 13.76, frame time 13.92 ms 2019-08-03 01:44:40.817 4C40 [NetworkServer] Info: Average latency: full 70.78(average 62.76) client 34.54 server 33.13 encoder 15.34 network 3.10 decoder 16.13, frame time 13.95 ms 2019-08-03 01:44:44.373 4C40 [NetworkServer] Info: Average latency: full 54.01(average 62.62) client 24.93 server 26.48 encoder 12.24 network 2.60 decoder 13.10, frame time 13.91 ms 2019-08-03 01:44:48.372 4C40 [NetworkServer] Info: Average latency: full 62.37(average 62.62) client 30.11 server 29.41 encoder 13.64 network 2.85 decoder 14.44, frame time 13.91 ms 2019-08-03 01:44:52.373 4C40 [NetworkServer] Info: Average latency: full 63.25(average 62.63) client 29.31 server 31.27 encoder 14.21 network 2.67 decoder 15.14, frame time 14.26 ms 2019-08-03 01:44:56.373 4C40 [NetworkServer] Info: Average latency: full 62.19(average 62.62) client 30.42 server 29.25 encoder 13.50 network 2.52 decoder 14.16, frame time 13.92 ms 2019-08-03 01:45:00.372 4C40 [NetworkServer] Info: Average latency: full 58.96(average 62.57) client 27.12 server 29.45 encoder 13.44 network 2.39 decoder 13.71, frame time 13.91 ms 2019-08-03 01:45:04.484 4C40 [NetworkServer] Info: Average latency: full 65.65(average 62.61) client 31.80 server 30.83 encoder 14.16 network 3.02 decoder 14.49, frame time 14.06 ms 2019-08-03 01:45:08.372 4C40 [NetworkServer] Info: Average latency: full 58.13(average 62.55) client 26.79 server 28.87 encoder 13.37 network 2.47 decoder 13.45, frame time 13.92 ms 2019-08-03 01:45:12.373 4C40 [NetworkServer] Info: Average latency: full 64.02(average 62.57) client 30.74 server 30.36 encoder 13.81 network 2.92 decoder 13.94, frame time 14.28 ms 2019-08-03 01:45:16.318 4C40 [NetworkServer] Info: Average latency: full 63.19(average 62.58) client 30.43 server 30.05 encoder 13.31 network 2.72 decoder 14.42, frame time 13.92 ms 2019-08-03 01:45:20.373 4C40 [NetworkServer] Info: Average latency: full 59.14(average 62.53) client 26.74 server 29.84 encoder 13.86 network 2.56 decoder 13.78, frame time 13.91 ms 2019-08-03 01:45:24.206 4C40 [NetworkServer] Info: Average latency: full 60.48(average 62.50) client 29.25 server 28.63 encoder 13.09 network 2.59 decoder 13.64, frame time 13.92 ms 2019-08-03 01:45:28.149 4C40 [NetworkServer] Info: Average latency: full 59.72(average 62.46) client 27.83 server 29.02 encoder 13.21 network 2.87 decoder 14.19, frame time 13.91 ms 2019-08-03 01:45:32.094 4C40 [NetworkServer] Info: Average latency: full 62.14(average 62.46) client 29.86 server 29.60 encoder 13.32 network 2.67 decoder 13.82, frame time 13.91 ms 2019-08-03 01:45:36.038 4C40 [NetworkServer] Info: Average latency: full 60.33(average 62.43) client 28.64 server 29.00 encoder 13.25 network 2.69 decoder 14.00, frame time 13.92 ms 2019-08-03 01:45:36.372 4C40 [NetworkServer] Info: Average latency: full 5.24(average 61.70) client 2.57 server 2.43 encoder 1.11 network 0.24 decoder 1.24, frame time 13.92 ms 2019-08-03 01:45:39.983 4C40 [NetworkServer] Info: Average latency: full 54.97(average 61.61) client 25.00 server 27.39 encoder 12.22 network 2.58 decoder 12.67, frame time 13.91 ms 2019-08-03 01:45:43.927 4C40 [NetworkServer] Info: Average latency: full 62.17(average 61.62) client 30.13 server 29.29 encoder 13.17 network 2.75 decoder 14.26, frame time 13.91 ms 2019-08-03 01:45:47.871 4C40 [NetworkServer] Info: Average latency: full 60.36(average 61.61) client 28.47 server 29.24 encoder 13.54 network 2.65 decoder 14.21, frame time 13.91 ms 2019-08-03 01:45:52.372 4C40 [NetworkServer] Info: Average latency: full 69.51(average 61.70) client 33.03 server 33.37 encoder 15.14 network 3.11 decoder 16.07, frame time 13.91 ms 2019-08-03 01:45:56.371 4C40 [NetworkServer] Info: Average latency: full 59.39(average 61.67) client 27.36 server 29.26 encoder 13.25 network 2.77 decoder 14.03, frame time 13.91 ms 2019-08-03 01:45:59.705 4C40 [NetworkServer] Info: Average latency: full 51.53(average 61.55) client 25.19 server 24.24 encoder 10.87 network 2.10 decoder 11.74, frame time 13.91 ms 2019-08-03 01:46:00.382 4C40 [NetworkServer] Info: Average latency: full 10.36(average 60.95) client 5.01 server 4.92 encoder 2.19 network 0.42 decoder 2.25, frame time 13.91 ms 2019-08-03 01:46:01.347 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.68 2019-08-03 01:46:08.371 4C40 [NetworkServer] Info: Average latency: full 119.47(average 61.63) client 56.22 server 58.44 encoder 26.67 network 4.80 decoder 27.34, frame time 13.91 ms 2019-08-03 01:46:11.539 4C40 [NetworkServer] Info: Average latency: full 48.77(average 61.48) client 22.81 server 23.76 encoder 10.81 network 2.20 decoder 10.98, frame time 13.95 ms 2019-08-03 01:46:12.375 4C40 [NetworkServer] Info: Average latency: full 12.53(average 60.93) client 5.85 server 6.13 encoder 2.87 network 0.54 decoder 2.85, frame time 13.91 ms 2019-08-03 01:46:16.371 4C40 [NetworkServer] Info: Average latency: full 58.88(average 60.90) client 27.46 server 28.94 encoder 13.28 network 2.48 decoder 13.74, frame time 13.93 ms 2019-08-03 01:46:20.371 4C40 [NetworkServer] Info: Average latency: full 59.15(average 60.88) client 27.32 server 29.20 encoder 13.37 network 2.64 decoder 13.86, frame time 13.91 ms 2019-08-03 01:46:24.371 4C40 [NetworkServer] Info: Average latency: full 62.10(average 60.90) client 30.38 server 29.06 encoder 13.25 network 2.65 decoder 14.13, frame time 13.91 ms 2019-08-03 01:46:27.317 4C40 [NetworkServer] Info: Average latency: full 44.21(average 60.72) client 20.23 server 21.85 encoder 10.01 network 2.13 decoder 10.65, frame time 13.91 ms 2019-08-03 01:46:28.372 4C40 [NetworkServer] Info: Average latency: full 15.57(average 60.23) client 7.06 server 7.80 encoder 3.57 network 0.71 decoder 3.59, frame time 14.01 ms 2019-08-03 01:46:31.260 4C40 [NetworkServer] Info: Average latency: full 42.67(average 60.04) client 19.28 server 21.44 encoder 9.82 network 1.95 decoder 10.06, frame time 13.91 ms 2019-08-03 01:46:35.205 4C40 [NetworkServer] Info: Average latency: full 63.07(average 60.08) client 31.01 server 29.26 encoder 13.53 network 2.80 decoder 14.11, frame time 13.92 ms 2019-08-03 01:46:36.372 4C40 [NetworkServer] Info: Average latency: full 17.28(average 59.63) client 8.05 server 8.53 encoder 3.98 network 0.70 decoder 3.94, frame time 13.92 ms 2019-08-03 01:46:40.371 4C40 [NetworkServer] Info: Average latency: full 59.40(average 59.63) client 27.62 server 29.32 encoder 13.41 network 2.46 decoder 13.66, frame time 13.91 ms 2019-08-03 01:46:43.108 4C40 [NetworkServer] Info: Average latency: full 45.51(average 59.48) client 23.25 server 20.25 encoder 9.23 network 2.00 decoder 12.56, frame time 13.91 ms 2019-08-03 01:46:44.228 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 71.276344 2019-08-03 01:46:44.385 4C40 [NetworkServer] Info: Average latency: full 19.39(average 59.08) client 8.90 server 9.50 encoder 4.35 network 0.99 decoder 4.82, frame time 13.93 ms 2019-08-03 01:46:45.629 4C40 [NetworkServer] Info: OnServiceUpdate() frame rate set: 72.000000 2019-08-03 01:46:47.051 4C40 [NetworkServer] Info: Average latency: full 41.24(average 58.90) client 19.53 server 19.73 encoder 8.99 network 1.98 decoder 9.56, frame time 14.05 ms 2019-08-03 01:46:48.385 4C40 [NetworkServer] Info: Average latency: full 20.38(average 58.52) client 9.76 server 9.66 encoder 4.36 network 0.95 decoder 4.68, frame time 14.05 ms 2019-08-03 01:46:56.384 4C40 [NetworkServer] Info: Average latency: full 124.17(average 59.16) client 60.34 server 58.26 encoder 26.44 network 5.56 decoder 30.95, frame time 13.93 ms 2019-08-03 01:47:16.384 4C40 [NetworkServer] Info: Average latency: full 306.62(average 61.57) client 145.34 server 146.37 encoder 66.75 network 14.90 decoder 70.64, frame time 13.91 ms 2019-08-03 01:47:18.607 4C40 [NetworkServer] Info: Average latency: full 33.79(average 61.30) client 15.65 server 16.52 encoder 7.64 network 1.63 decoder 7.80, frame time 13.91 ms 2019-08-03 01:47:20.384 4C40 [NetworkServer] Info: Average latency: full 25.93(average 60.96) client 11.69 server 12.91 encoder 5.90 network 1.32 decoder 6.03, frame time 13.91 ms 2019-08-03 01:47:28.386 4C40 [NetworkServer] Info: Average latency: full 122.39(average 61.54) client 58.15 server 58.44 encoder 26.73 network 5.80 decoder 28.57, frame time 13.91 ms 2019-08-03 01:47:30.440 4C40 [NetworkServer] Info: Average latency: full 32.94(average 61.27) client 16.37 server 14.99 encoder 6.80 network 1.58 decoder 7.14, frame time 13.92 ms 2019-08-03 01:47:31.561 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:31.561 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:31.855 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:31.855 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.078 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.079 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.357 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.357 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.385 4C40 [NetworkServer] Info: Average latency: full 32.18(average 61.00) client 16.36 server 14.39 encoder 6.35 network 1.43 decoder 6.57, frame time 13.92 ms 2019-08-03 01:47:32.603 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.603 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.874 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:32.874 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.107 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.109 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.375 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.382 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.619 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.619 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.925 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=29994 newID=29996 2019-08-03 01:47:33.926 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=29994 newID=29997 2019-08-03 01:47:33.926 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=29995 dist=-2 2019-08-03 01:47:33.927 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:33.931 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.241 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30058 newID=30060 2019-08-03 01:47:34.241 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30059 dist=-1 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30060 newID=30081 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30061 dist=-20 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30062 dist=-19 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30063 dist=-18 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30064 dist=-17 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30065 dist=-16 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30066 dist=-15 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30067 dist=-14 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30068 dist=-13 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30069 dist=-12 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30070 dist=-11 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30071 dist=-10 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30072 dist=-9 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30073 dist=-8 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30074 dist=-7 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30075 dist=-6 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30076 dist=-5 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30077 dist=-4 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30078 dist=-3 2019-08-03 01:47:34.242 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30079 dist=-2 2019-08-03 01:47:34.243 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30080 dist=-1 2019-08-03 01:47:34.243 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.249 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.563 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.569 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.882 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:34.888 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.196 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30277 newID=30280 2019-08-03 01:47:35.196 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30280 newID=30283 2019-08-03 01:47:35.196 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30278 dist=-5 2019-08-03 01:47:35.196 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30283 newID=30298 2019-08-03 01:47:35.196 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30279 dist=-19 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30281 dist=-17 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30282 dist=-16 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30284 dist=-14 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30285 dist=-13 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30286 dist=-12 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30287 dist=-11 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30288 dist=-10 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30289 dist=-9 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30290 dist=-8 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30291 dist=-7 2019-08-03 01:47:35.197 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30292 dist=-6 2019-08-03 01:47:35.198 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30293 dist=-5 2019-08-03 01:47:35.198 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30294 dist=-4 2019-08-03 01:47:35.198 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30295 dist=-3 2019-08-03 01:47:35.198 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30296 dist=-2 2019-08-03 01:47:35.198 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30297 dist=-1 2019-08-03 01:47:35.205 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.212 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.517 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.525 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.837 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:35.842 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.152 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.157 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.467 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.67 2019-08-03 01:47:36.471 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.475 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.783 4C40 [FlowCtrlProtocol] Info: Message send from gap. oldID=30644 newID=30665 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30645 dist=-23 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30646 dist=-22 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30647 dist=-21 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30648 dist=-20 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30649 dist=-19 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30650 dist=-18 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30651 dist=-17 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30652 dist=-16 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30653 dist=-15 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30654 dist=-14 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30655 dist=-13 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30656 dist=-12 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30657 dist=-11 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30658 dist=-10 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30659 dist=-9 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30660 dist=-8 2019-08-03 01:47:36.784 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30661 dist=-7 2019-08-03 01:47:36.785 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30662 dist=-6 2019-08-03 01:47:36.785 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30663 dist=-5 2019-08-03 01:47:36.785 4C40 [FlowCtrlProtocol] Info: Message is old - drop it. ID=30664 dist=-4 2019-08-03 01:47:36.789 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:36.796 4C40 [NetworkServer] Info: OnServiceMessage - VIDEO_OP_CODE_FORCE_IDR 2019-08-03 01:47:38.329 4C40 [NetworkServer] Info: Average latency: full 126.47(average 61.60) client 68.29 server 47.83 encoder 19.59 network 10.36 decoder 18.55, frame time 13.92 ms 2019-08-03 01:47:40.384 4C40 [NetworkServer] Info: Average latency: full 31.32(average 61.33) client 14.58 server 15.17 encoder 6.95 network 1.57 decoder 7.38, frame time 13.92 ms 2019-08-03 01:47:42.273 4C40 [NetworkServer] Info: Average latency: full 28.44(average 61.03) client 12.96 server 14.08 encoder 6.49 network 1.40 decoder 6.77, frame time 13.91 ms 2019-08-03 01:47:44.384 4C40 [NetworkServer] Info: Average latency: full 33.61(average 60.79) client 16.56 server 15.47 encoder 7.15 network 1.58 decoder 7.69, frame time 13.91 ms 2019-08-03 01:47:46.217 4C40 [NetworkServer] Info: Average latency: full 28.87(average 60.51) client 13.87 server 13.64 encoder 6.44 network 1.36 decoder 6.56, frame time 13.92 ms 2019-08-03 01:47:54.106 4C40 [NetworkServer] Info: Average latency: full 119.88(average 61.03) client 56.18 server 58.52 encoder 26.79 network 5.18 decoder 27.79, frame time 13.91 ms 2019-08-03 01:48:12.384 4C40 [NetworkServer] Info: Average latency: full 278.30(average 62.92) client 133.42 server 133.65 encoder 60.82 network 11.22 decoder 63.16, frame time 14.00 ms 2019-08-03 01:48:13.828 4C40 [NetworkServer] Info: Average latency: full 21.39(average 62.56) client 9.67 server 10.65 encoder 4.87 network 1.07 decoder 5.21, frame time 13.92 ms 2019-08-03 01:48:16.383 4C40 [NetworkServer] Info: Average latency: full 41.64(average 62.38) client 20.99 server 18.65 encoder 8.47 network 2.00 decoder 10.34, frame time 13.99 ms 2019-08-03 01:48:21.717 4C40 [NetworkServer] Info: Average latency: full 82.06(average 62.55) client 38.85 server 39.29 encoder 17.85 network 3.92 decoder 19.26, frame time 13.91 ms 2019-08-03 01:48:24.382 4C40 [NetworkServer] Info: Average latency: full 41.81(average 62.37) client 20.57 server 19.34 encoder 8.76 network 1.90 decoder 10.05, frame time 13.91 ms 2019-08-03 01:48:33.550 4C40 [NetworkServer] Info: Average latency: full 144.63(average 63.06) client 69.81 server 68.15 encoder 31.40 network 6.67 decoder 34.31, frame time 13.91 ms 2019-08-03 01:48:36.382 4C40 [NetworkServer] Info: Average latency: full 42.42(average 62.89) client 19.87 server 20.87 encoder 9.59 network 1.68 decoder 10.11, frame time 13.91 ms 2019-08-03 01:48:37.493 4C40 [NetworkServer] Info: Average latency: full 16.68(average 62.51) client 7.74 server 8.26 encoder 3.78 network 0.69 decoder 3.90, frame time 14.07 ms 2019-08-03 01:48:40.382 4C40 [NetworkServer] Info: Average latency: full 42.52(average 62.35) client 19.76 server 21.03 encoder 9.60 network 1.72 decoder 9.92, frame time 13.91 ms 2019-08-03 01:48:41.438 4C40 [NetworkServer] Info: Average latency: full 16.84(average 61.98) client 8.53 server 7.74 encoder 3.53 network 0.58 decoder 3.60, frame time 13.91 ms 2019-08-03 01:48:45.383 4C40 [NetworkServer] Info: Average latency: full 59.18(average 61.96) client 27.59 server 29.38 encoder 13.43 network 2.22 decoder 13.22, frame time 14.02 ms 2019-08-03 01:48:49.326 4C40 [NetworkServer] Info: Average latency: full 59.25(average 61.93) client 27.78 server 29.14 encoder 13.39 network 2.33 decoder 13.44, frame time 13.99 ms 2019-08-03 01:48:53.271 4C40 [NetworkServer] Info: Average latency: full 59.53(average 61.92) client 27.46 server 29.70 encoder 13.60 network 2.37 decoder 13.70, frame time 14.07 ms 2019-08-03 01:48:56.382 4C40 [NetworkServer] Info: Average latency: full 53.77(average 61.85) client 26.27 server 25.66 encoder 11.20 network 1.84 decoder 9.50, frame time 15.91 ms 2019-08-03 01:49:00.383 4C40 [NetworkServer] Info: Average latency: full 87.49(average 62.05) client 40.36 server 44.62 encoder 16.42 network 2.51 decoder 9.98, frame time 20.32 ms 2019-08-03 01:49:08.382 4C40 [NetworkServer] Info: Average latency: full 122.42(average 62.52) client 57.98 server 59.93 encoder 27.27 network 4.51 decoder 26.19, frame time 14.34 ms 2019-08-03 01:49:09.051 4C40 [NetworkServer] Info: Average latency: full 12.04(average 62.13) client 6.26 server 5.37 encoder 2.33 network 0.40 decoder 1.96, frame time 16.33 ms 2019-08-03 01:49:12.383 4C40 [NetworkServer] Info: Average latency: full 52.26(average 62.06) client 24.56 server 24.89 encoder 11.49 network 2.81 decoder 12.21, frame time 14.10 ms 2019-08-03 01:49:12.993 4C40 [NetworkServer] Info: Average latency: full 9.91(average 61.66) client 4.70 server 4.65 encoder 2.26 network 0.56 decoder 2.52, frame time 13.94 ms 2019-08-03 01:49:14.371 4C40 [DeviceDriver] Info: Device (/hmd) battery level is 0.66 2019-08-03 01:49:16.938 4C40 [NetworkServer] Info: Average latency: full 62.73(average 61.67) client 29.74 server 29.63 encoder 13.74 network 3.36 decoder 15.02, frame time 13.97 ms 2019-08-03 01:49:20.882 4C40 [NetworkServer] Info: Average latency: full 60.28(average 61.66) client 27.53 server 29.83 encoder 13.90 network 2.92 decoder 14.26, frame time 13.95 ms 2019-08-03 01:49:24.827 4C40 [NetworkServer] Info: Average latency: full 63.45(average 61.67) client 30.30 server 29.74 encoder 13.81 network 3.40 decoder 15.66, frame time 13.93 ms 2019-08-03 01:49:28.381 4C40 [NetworkServer] Info: Average latency: full 56.57(average 61.64) client 26.81 server 26.78 encoder 12.26 network 2.98 decoder 14.66, frame time 13.92 ms 2019-08-03 01:49:32.381 4C40 [NetworkServer] Info: Average latency: full 59.84(average 61.62) client 26.70 server 30.13 encoder 13.85 network 3.01 decoder 14.42, frame time 13.93 ms 2019-08-03 01:49:36.382 4C40 [NetworkServer] Info: Average latency: full 60.32(average 61.61) client 28.54 server 29.45 encoder 13.73 network 2.33 decoder 13.04, frame time 13.92 ms 2019-08-03 01:49:36.659 4C40 [NetworkServer] Info: Average latency: full 4.04(average 61.20) client 1.78 server 2.09 encoder 0.98 network 0.17 decoder 0.97, frame time 13.92 ms 2019-08-03 01:49:40.381 4C40 [NetworkServer] Info: Average latency: full 55.48(average 61.16) client 26.18 server 27.40 encoder 12.78 network 1.91 decoder 11.64, frame time 13.93 ms 2019-08-03 01:49:42.734 4C40 [NetworkServer] Info: StopStreaming() start 2019-08-03 01:49:42.734 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/ctrlRight) 2019-08-03 01:49:42.734 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/ctrlLeft) 2019-08-03 01:49:42.734 4C40 [IServerTrackedDeviceProviderImpl] Info: OnDeviceDisconnected(/hmd) 2019-08-03 01:49:42.734 4C40 [DisplayDriver] Info: Display '/hmd' disconnected. 2019-08-03 01:49:42.734 4C40 [VideoPipeline] Info: Terminate()... 2019-08-03 01:49:42.734 4C40 [VideoPipeline] Info: Waiting for the poller thread to stop... 2019-08-03 01:49:42.746 4C40 [VideoPipeline] Info: Shutting down encoder... 2019-08-03 01:49:42.754 4C40 [VideoPipeline] Info: Shutting down CSC... 2019-08-03 01:49:42.756 4C40 [AudioPipeline] Info: Terminate() start 2019-08-03 01:49:42.762 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:49:42.782 4C40 [AMFAudioCaptureImpl] Info: Terminate() 2019-08-03 01:49:42.782 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 55445, Queried 55445 2019-08-03 01:49:42.783 4C40 [AMFAudioConverterFFMPEGImpl] Info: Submitted 0, Queried 0 2019-08-03 01:49:42.783 4C40 [AMFAudioEncoderFFMPEGImpl] Info: Submitted 55445, Queried 23877 2019-08-03 01:49:42.784 4C40 [AudioPipeline] Info: Terminate() end 2019-08-03 01:49:42.784 4C40 [NetworkServer] Info: Sent StopSensor 2019-08-03 01:49:42.784 4C40 [NetworkServer] Info: OnServiceStop: Client connection terminated, reason: stop command 2019-08-03 01:49:42.784 4C40 [NetworkServer] Info: OnServiceMessage - SERVICE_OP_CODE_STOP 2019-08-03 01:49:47.786 4C40 [SessionManager] Info: Session timed out 2019-08-03 01:49:47.786 4C40 [SessionManager] Info: Session timed out 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRUDPServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: Session destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRUDPServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: Session destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRDiscoveryServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRUDPServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: Session destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRDiscoveryServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: AWVRUDPServerSessionImpl destroyed 2019-08-03 01:49:47.786 4C40 [WVRServerSession] Info: Session destroyed 2019-08-03 01:49:56.386 680C [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=22940 2019-08-03 01:49:56.386 6854 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=24600 2019-08-03 01:49:56.487 6814 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=1084 2019-08-03 01:49:56.487 6014 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=26404 2019-08-03 01:49:56.591 673C [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=22836 2019-08-03 01:49:56.690 681C [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=11432 2019-08-03 01:49:57.310 5C90 [DisplayDriver] Info: DestroyAllSwapTextureSets() succeded process=8972

Chanhoon21 commented 5 years ago

cannot fix?

MikhailAMD commented 5 years ago

Hey, it is long weekend in Canada, we will be back Tuesday. Meanwhile, can you enable audio at your PC and check if the audio interruption happens on Quest only or on PC as well? In the log I see some network problems. Can you try to be closer to the router?

Chanhoon21 commented 5 years ago

only happens on quest. i can hear through pc. im right front of router about 10cm. what happen?

GennadiyAMD commented 5 years ago

@chanhoon - could you please capture a logcat from your device? Leave the USB cable connected, on the DOS prompt enter abd logcat | find "AudioPipeline" > c:\audiologcat.txt, start the app and let the sound fail. Once fails, press Ctrl+C to terminate the log. Please attach the captured log.

Thank you!

Chanhoon21 commented 5 years ago

how to use adb logcat on (window+R+cmd)? i typed abd logcat | find "AudioPipeline" > c:\audiologcat.txt,. i says 'abd' is not recognized as an internal or external command, operable program or batch file.

GennadiyAMD commented 5 years ago

@chanhoon - adb, not abd. Sorry, my typo. It's the same tool you used to side-load the APK.

Chanhoon21 commented 5 years ago

i used program called side-quest to side-load. is there command shell to put abd logcat | find "AudioPipeline" > c:\audiologcat.txt in side-quest program? sorry i have no expertise about developing

GennadiyAMD commented 5 years ago

@chanhoon - ok, let's try to capture it another way then:

Unzip the app.settings file from the attached zip.
Connect your USB cable and open Windows Explorer. Find Quest under "This PC" and navigate to Internal shared storage->Android\data. Create a folder named com.amd.wirelesshmd if it doesn't exist and inside create another subfolder called files (all lowercase).
Copy the app.settings file to the Android\data\com.amd.wirelesshmd\files folder.
Disconnect the USB cable
Start the ReLive VR app and reproduce the problem. Close the ReLive VR app.
Connect the USB cable back, navigate to the Android\data\com.amd.wirelesshmd\files folder again and you should find a file named _amf_wvrlog.txt right beside the app.settings file.
Delete the app.settings file or rename it to something else
Copy the _amf_wvrlog.txt file back to the PC, zip it (it might get large) and attach to your response. Delete the _amf_wvrlog.txt file from you headset so that it doesn't waste any space.

Thanks! app.settings.zip

GennadiyAMD commented 5 years ago

@totolook - could you please capture the same log following the above procedure as well? Thanks!

Chanhoon21 commented 5 years ago

amf_wvr_log.zip

hi i reproduce problem and attached file. plz analyze!

GennadiyAMD commented 5 years ago

@totolook , @chanhoon - thank you very much, this helped a lot! I've just uploaded a fix for this (1.0.17) - I'd highly appreciate if you could try it out and let me know if this resolves the issue. You might hear occasional hiccups from time to time, but audio should not cut off completely.

Chanhoon21 commented 5 years ago

Thanks what should I do for now then? Just play or redownload something?

MikhailAMD commented 5 years ago

Sideload the new APK to the headset the same way as you did initally.

Chanhoon21 commented 5 years ago

i'm so sorry for bothering. but it still produce problem here is my log amf_wvr_log.zip

GennadiyAMD commented 5 years ago

@chanhoon - nothing to be sorry for, if it doesn't work, it still needs to be fixed. Unfortunately we cannot reproduce this problem on our end and have to rely on your feedback, my apologies for that. Would it be possible to contact you privately somehow? We would like to share a private build for the PC side with you to try and capture some additional log, if you don't mind.

Thank you very much for your help!

Chanhoon21 commented 5 years ago

Okay. Thanks for caring me. Let's try after holiday. It's long holiday in Korea until Sunday. I'll be back after holiday

Chanhoon21 commented 5 years ago

i'm back so now what should i do?

MikhailAMD commented 5 years ago

Write me a private email at: Mikhail[dot]mironov[att]amd[dot]com

romanpekarskiy commented 5 years ago

Had same issues sometimes while streaming Elite Dangerous. Sound just stops working, mostly in situation when session changes, for example changing ship, loading, multicrew connect. Ill try to provide logs next time when it happens.

Edit: add additional info after switching Quest to Oculus home and back to AMD Relive helps a bit, sound goes back.

BTW is there any discussion community about AMD Relive VR? May be some Discord server, or subreddit? ill be glad to be a part of it. also I wonder is mic will be supported sometime.

GennadiyAMD commented 5 years ago

@romanpekarskiy - we haven't been able to reproduce this issue in-house, seems like it's specific to a combination of PC-headset, so we're trying to address it somewhat blindly. Here's a link to a test build of the driver on the PC side: https://www.dropbox.com/s/3q3ewy9y79xl2tc/driver_amdwvr.dll?dl=0

Please try the following:

Shut down SteamVR
Rename the original DLL in c:\Program Files\AMD\WVR\OpenVR\bin\win64 to something else
Copy the DLL from the link there. If Windows prevents you from placing a downloaded file there, put in on your desktop first and then move it there.
Play your game, see if the issue still happens. If it does, please send us both the PC and the device log (the instructions to enable it are in this thread posted by me on Aug 8 - just scroll a few messages above)

Thanks in advance and appreciate your help.

There are several threads on Reddit discussing ReLive VR, but no official one.

GennadiyAMD commented 5 years ago

@romanpekarskiy , @chanhoon - sorry to bug you, have you had a chance to try the private build we shared with you? Did it make any difference?

Thanks you!

romanpekarskiy commented 5 years ago

@GennadiyAMD personally i couldnt reproduce that issue, but im mostly switched to PC wired headphones due im playing Elite Dangerous on PC last days, so im OK with visual glitches caused by network connection, but i cant stand audio glitches, that why im not using Quest audio for now. ill try today test it with Quest audio to give some information

romanpekarskiy commented 5 years ago

@GennadiyAMD I have lost sound while playin Lone Echo when updated drivers to 19.9.1, there is the log file: AMDWirelessVR.log

I have downloaded private build again and replaced original dll with new one, will check it out again. If it fails again that will be a bit difficult to reproduce it with USB connected tho, but ill try if it happens

GennadiyAMD commented 5 years ago

19.9.1 doesn't have the experimental code, so I fully expected it to fail. The private build test is the interesting one.

You don't need USB connected. Take a look at one of the messages higher in this thread, there's the app.settings.zip file attached to it and instructions are in that message. This will allow to create a log file on the headset itself that you can copy to the PC afterwards.

Thanks so much for helping out with this!

ClarkFamily commented 5 years ago

I was able to compare the production dll in 19.9.1 with the experimental one linked above.

19.9.1: Audio begins to sputter before cutting out completely. Sometimes it comes back, other times the only remedy is a full reboot. https://gist.github.com/ClarkFamily/b60188c260bb2be48e699f18bce94da5

Experimental: ReLive on the Quest crashed at 09-11 11:40:14.705 but I could tell that the wireless connection was getting a little shaky since there were some dropped frames and visual stutter. There were some audio pops here and there, but nothing like I experienced with 19.9.1 https://gist.github.com/ClarkFamily/5190f3e19d25b7c03da8c0843169c844

GennadiyAMD commented 5 years ago

@romanpekarskiy @ClarkFamily - thank you very much! May I ask you to try another experimental build - https://www.dropbox.com/s/3q3ewy9y79xl2tc/driver_amdwvr.dll?dl=0

Really appreciate you help, folks!

ClarkFamily commented 5 years ago

Experimental_2: I didn't get as long to play, but I did not experience any audio issues whatsoever. https://gist.github.com/ClarkFamily/089772cea9c9bd1545a19df078011ba9

GennadiyAMD commented 5 years ago

@ClarkFamily - thank you so much! We will promote this fix to the next driver update, but in the meantime please let us know if you observe any issues with the audio.

Thanks again!

ClarkFamily commented 5 years ago

I did get a longer play session last night. Sadly, I wasn't in a place where I could be hooked up to logging. About 60-90 minutes into the session I observed sputtering video and audio as I did in 19.9.1 (granted I got about 1000% more playtime than before). The video returned to high quality and clear while audio remained disconnected. Closing ReLive and reconnecting (to the same Steam VR session) did not resolve. I didn't test restarting Steam VR to see if audio returned.

I'll see if I can get a logcat without filtering on my next session to see if it can paint a clearer picture.

GennadiyAMD commented 5 years ago

@ClarkFamily - you don't really need to be connected as long as you have enough disk space on the headset. If you scroll up this thread, there's a message from me on Aug 8 with instructions and a zip file attached containing a config file you can place on the headset to enable logging. This will make the headset to record a log for the whole session and you can just copy that file to the PC afterwards. Just don't forget to delete the config file afterwards to disable the log.

Which game were you playing when you observed this? And what is your PC config (motherboard/CPU/audio card if any)?

Thanks!

ClarkFamily commented 5 years ago

I'm not running the modified app.settings to log onto the headset. I prefer to be wired up to adb over the usb-c so I can do my own real-time log filtering in the terminal.

I experience the same issue in multiple games (Moss, Tetris Effect) and even once in Steam VR home.

Motherboard: MSI X470 Gaming PRO
CPU: AMD Ryzen 5 2600X
Audio: Berhinger: XR-18
Graphics: XFX Radeon RX 580 GTS XXX Edition

GennadiyAMD commented 5 years ago

@ClarkFamily - we log the exact same data into logcat and the file, so the application trace data obtained from either log would be identical.

Also, could you grab the PC side log as well - c:\Program Files (x86)\Steam\logs\AMDWirelessVR.log

Just to confirm - the Behringer XR-18 is a mixer, not a sound card, so it's hooked up to one of the audio outputs on the motherboard, correct?

Thanks!

ClarkFamily commented 5 years ago

XR-18 is set up as a USB sound card in my setup. I can disable it in Windows and unplug it altogether to remove it from the equation. I'll get a session going and get back to you with the logcat and AMDWirelessVR.log.

ClarkFamily commented 5 years ago

I played a session that lasted an hour an 10 minutes without the XR18 plugged in and the Windows playback device set to "Speakers (Steam Streaming Speakers)". Within the last 10 minutes of the session I had a video/audio lockup that froze the video in the headset, but I could see the head tracking and controllers still interacting with the game on the computer monitor. I closed Relive on the headset and reconnected; video was back to normal, sound was gone.

The logcat for the last 10 minutes minus these lines to save space

00-00 00:00:00.000 3519 5518 W libOpenSLES: Leaving BufferQueue::Enqueue (SL_RESULT_BUFFER_INSUFFICIENT)

https://gist.github.com/ClarkFamily/4a4def687f1ce052f19237745b328ecc

The AMDWirelessVR.log: https://gist.github.com/ClarkFamily/93444af1551d6aa370dbe130ae0073a0

GennadiyAMD commented 5 years ago

@ClarkFamily - unfortunately the logcat ended too soon - the last thing that got captured was the network hiccup, which generated a lot of messages, so we couldn't see what happened with the audio after that. May I ask you to try capturing it again, but with the "audio" filter applied to filter out unrelated messages? Thanks a lot!

MikhailAMD commented 5 years ago

@romanpekarskiy @ClarkFamily There is a new version of the DLL to try: https://www.dropbox.com/s/aqcxd9sf1rhmo6n/driver_amdwvr_v002.zip?dl=0

ClarkFamily commented 5 years ago

That logcat does contain the audio crash, I believe it's somewhere around the 14:47-14:52 mark. Anything beyond 14:57 in the AMDWirelessVR.log is me shutting down Steam VR.

I'll give the new dll a shot. Should I still filter to just audio?

MikhailAMD commented 5 years ago

Yes please, filter for audio. Thanks.

romanpekarskiy commented 5 years ago

Lost audio again with new .dll there are my log files amf_wvr_log.txt EDIT ~~ AMDWirelessVR.log sorry, seems that this is a wrong file, ill upload a new bunch when issue reproduces again

romanpekarskiy commented 5 years ago

One more reproduce AMDWirelessVR.log amf_wvr_log.txt

GennadiyAMD commented 5 years ago

@romanpekarskiy @ClarkFamily - please try the latest client APK with the experimental DLL. Thanks!

ClarkFamily commented 5 years ago

Held up pretty well for over an hour before crash. Video locks up at 14:19 - 14:20 forcing me to close and reopen ReLive app. Upon reconnection, no sound.

https://gist.github.com/ClarkFamily/1fd187b028fd850a97f0af0b50c33267

GennadiyAMD commented 5 years ago

@ClarkFamily @romanpekarskiy - thank you so much for the valuable data! Found one potential place that could trigger it - please try this: https://www.dropbox.com/s/fk2x0x5pwxowfeo/driver_amdwvr_v003.zip?dl=0

Please note that there are two DLLs in the archive, it's important to update both (both are located in the same folder)

Thanks so much again for your help and sorry for the trouble!

ClarkFamily commented 5 years ago

While I haven't had the time to get any really long sessions in, i've been playing games over reLive for the past couple days and have not ran into any audio drops.

BrotherO4 commented 5 years ago

it seems i also get this bug as well. my specs would be the 5700 xt with an 3800x as the cpu. i first thought the bug was due to open VR. after a few tries..i think my issue is relating to ALVR. once i remove the drivers and uninstalled it. i got sound back. its worth a look i guess for you all.

ClarkFamily commented 5 years ago

I've had a lot more time with the fixed dlls over the past two weeks and haven't hit the disappearing audio problem at all.

BrotherO4 commented 5 years ago

I've had a lot more time with the fixed dlls over the past two weeks and haven't hit the disappearing audio problem at all.

i did but i found the direct source of my issue. it had something too do with ALVR. once i removed it from my pc it all came back smoothly.

GPUOpen-LibrariesAndSDKs / Radeon-ReLive-VR

audio is working but sometimes not working #15