Closed akesher123 closed 1 year ago
We can't help without client logs. We also don't recommend using the LibVLC implementation (it's off by default for a reason).
Client logs below from logcat. Sorry for the huge dump, I guess a lot happens in 15 seconds. As for LibVLC vs Exoplayer, while exoplayer works really well 90% of the time, every now and then I run into an issue that is simply solved by using LibVLC. The most recent example of this is stuttery playback on interlaced content with Exoplayer that LibVLC handles like a champ. In the past I've had subtitle issues and files refusing to play issues (though not recently) that have kept me from keeping Exoplayer on all the time.
Type Time PID Tag Message
2022-12-13T20:09:18.198Z 3700 system_server Background concurrent copying GC freed 334638(16MB) AllocSpace objects, 72(3008KB) LOS objects, 49% free, 18MB/37MB, paused 142us total 233.464ms
2022-12-13T20:09:17.788Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Sessions/Playing/Progress
2022-12-13T20:09:17.778Z 3371 NvOsDebugPrintf NvRmStreamFree: WARN: pStream is NULL
2022-12-13T20:09:17.776Z 3371 hdcp up tsec_version: 1
2022-12-13T20:09:17.722Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:17.691Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Sessions/Playing/Progress
2022-12-13T20:09:17.689Z 29579 PlaybackController pause called at 1778
2022-12-13T20:09:17.682Z 3700 WindowManager handleComboKeys keyCode: 23, keyAction: 1
2022-12-13T20:09:17.575Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:17.575Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:17.574Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:17.557Z 3700 WindowManager handleComboKeys keyCode: 23, keyAction: 0
2022-12-13T20:09:17.537Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:17.537Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:17.537Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:17.537Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:17.537Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:17.007Z 3700 WindowManager handleComboKeys keyCode: 19, keyAction: 1
2022-12-13T20:09:16.891Z 6855 DeviceUnlockedTag DeviceUnlockedTag.notifyDeviceLockStatusChanged():38 Notify device unlocked.
2022-12-13T20:09:16.891Z 6855 GoogleInputMethodService GoogleInputMethodService.onStartInput():2045
2022-12-13T20:09:16.891Z 6855 GoogleInputMethodService GoogleInputMethodService.onFinishInput():3466
2022-12-13T20:09:16.882Z 3700 WindowManager handleComboKeys keyCode: 19, keyAction: 0
2022-12-13T20:09:16.854Z 3889 nv_bt_stats btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2022-12-13T20:09:16.536Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:16.535Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:16.534Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:16.506Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:16.506Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:16.506Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:16.506Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:16.505Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:16.444Z 29579 VLC [0000002fe594d1c0/5476] libvlc window: request 3 not implemented
2022-12-13T20:09:15.832Z 3700 WindowManager handleComboKeys keyCode: 20, keyAction: 1
2022-12-13T20:09:15.704Z 545 nvaudio_hw Stream has IEC958 data, set dev configs(rate=192000,ch=2,periods(count=4, size=4096)
2022-12-13T20:09:15.696Z 545 FMQ grantorIdx must be less than 3
2022-12-13T20:09:15.688Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.683Z 544 AudioFlinger pause because of UNDERRUN, framesReady = 0,minFrames = 4096, mFormat = 0xd000000
2022-12-13T20:09:15.682Z 3700 WindowManager handleComboKeys keyCode: 20, keyAction: 0
2022-12-13T20:09:15.679Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.667Z 545 nvaudio_hw num_sinks = 1 out->num_devices = 0 out->devices1[out->num_devices] = 1024
2022-12-13T20:09:15.667Z 545 nvaudio_hw nvaudio_dev_create_audio_patch(): handle generated, handle:147
2022-12-13T20:09:15.644Z 29579 AudioTrack See the documentation of AudioTrack() for what to use instead with android.media.AudioAttributes to qualify your playback use case
2022-12-13T20:09:15.644Z 29579 AudioTrack Use of stream types is deprecated for operations other than volume control
2022-12-13T20:09:15.640Z 544 AF::TrackHandle OpPlayAudio: track:980 usage:1 not muted
2022-12-13T20:09:15.638Z 544 APM_AudioPolicyManager getOutputForAttrInt returns output 5469 selectedDeviceId 1554 for device {type:0x400,@:}, sampling rate 192000, format 0xd000000, channel mask 0x3, flags 0x1 stream AUDIO_STREAM_MUSIC
2022-12-13T20:09:15.630Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.630Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 8
2022-12-13T20:09:15.629Z 544 AudioFlinger AudioFlinger's thread 0x25782c8f00 tid=21639 ready to run
2022-12-13T20:09:15.628Z 544 AudioFlinger HAL output buffer size 4096 frames, normal sink buffer size 4096 frames
2022-12-13T20:09:15.626Z 29579 VLC [00000030158a1dc0/5476] libvlc vout display: picture way too early to release at time
2022-12-13T20:09:15.624Z 544 AudioFlinger openOutput() this 0x25282b10d0, module 10 Device type:0x400,@:, SamplingRate 192000, Format 0xd000000, Channels 0x3, flags 0x1
2022-12-13T20:09:15.623Z 546 StreamInMSDHAL ~StreamIn(0x445c1270)
2022-12-13T20:09:15.607Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.570Z 29579 VLC [00000030158a1dc0/5476] libvlc vout display: picture way too early to release at time
2022-12-13T20:09:15.558Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Sessions/Playing/Progress
2022-12-13T20:09:15.543Z 545 nvaudio_hw Stream has IEC958 data, set dev configs(rate=48000,ch=6,periods(count=2, size=256)
2022-12-13T20:09:15.537Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.536Z 546 DeviceMSDHAL releaseAudioPatch() patch:161 released
2022-12-13T20:09:15.535Z 544 FMQ grantorIdx must be less than 3
2022-12-13T20:09:15.535Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.535Z 545 FMQ grantorIdx must be less than 3
2022-12-13T20:09:15.534Z 546 MS12OutputFramer getBuffer() resizing buffer to 24576
2022-12-13T20:09:15.531Z 546 msf_ddpenc_node ddpenc_node_reopen_encoder: ddp_enc set up in ddp:1, atmos_transcode:0
2022-12-13T20:09:15.528Z 3371 hwcomposer SuperRes: Selecting filter NONE
2022-12-13T20:09:15.527Z 544 AudioFlinger::PatchPanel clearConnections() mRecord.handle 3612 mPlayback.handle 3620
2022-12-13T20:09:15.527Z 544 AudioFlinger::PatchPanel releaseAudioPatch handle: its a swPatch - clearConnections 3628
2022-12-13T20:09:15.527Z 544 APM_AudioPolicyManager releaseAudioPatchInternal: call AF->releaseAudioPatch patchHandle:1564, AfHandle:3628
2022-12-13T20:09:15.525Z 545 modules.usbaudio.audio_hal Set use_msd_patch to 0
2022-12-13T20:09:15.525Z 544 bt_a2dp_hw set use_msd_patch to 0
2022-12-13T20:09:15.525Z 545 nvaudio_hw use_msd_patch 0
2022-12-13T20:09:15.523Z 545 modules.usbaudio.audio_hal Set use_msd_patch to 0
2022-12-13T20:09:15.523Z 545 nvaudio_hw use_msd_patch 0
2022-12-13T20:09:15.523Z 544 APM_AudioPolicyManager getOutputForDevices() could not find output for stream 3, sampling rate 192000, format 0xd000000, channels 0x3, flags 0x1
2022-12-13T20:09:15.523Z 544 APM_AudioPolicyManager getOutputForAttrInt() device {type:0x400,@:}, sampling rate 192000, format 0xd000000, channel mask 0x3, flags 0x1 stream AUDIO_STREAM_MUSIC
2022-12-13T20:09:15.519Z 544 FMQ grantorIdx must be less than 3
2022-12-13T20:09:15.519Z 546 AudioDump setFileName()
2022-12-13T20:09:15.518Z 546 FMQ grantorIdx must be less than 3
2022-12-13T20:09:15.517Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.513Z 29579 VLC [00000030158a1dc0/5476] libvlc vout display: picture way too early to release at time
2022-12-13T20:09:15.513Z 29579 VLC [00000030258c8560/547e] libvlc decoder: output: 326 unknown, 1920x1080 stride 1920 1080, crop 0 0 0 0
2022-12-13T20:09:15.512Z 29579 ACodec Scaled Width is 1920, height is 1080
2022-12-13T20:09:15.512Z 29579 ACodec Get OMX_IndexConfigScale: 0x10000/0x10000
2022-12-13T20:09:15.504Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:15.504Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:15.503Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.503Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:15.501Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.501Z 544 APM_AudioPolicyManager setMsdPatch() Patch created from MSD_IN to device:type:0x400,@: (format:0xa000000 channels:0x17000c samplerate:48000, patch_id:0)
2022-12-13T20:09:15.501Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.500Z 544 AudioFlinger::PatchPanel A non empty Patch Endpoint leaked, handle 3620
2022-12-13T20:09:15.500Z 544 AudioFlinger::PatchPanel A non empty Patch Endpoint leaked, handle 3612
2022-12-13T20:09:15.500Z 544 AudioFlinger::PatchPanel Software patch connections leaked 3612 3620
2022-12-13T20:09:15.500Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.500Z 544 AudioFlinger::PatchPanel createAudioPatch() success, handle:3628
2022-12-13T20:09:15.500Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.500Z 544 AF::TrackHandle OpPlayAudio: not muting track:979 usage:0 for service UID 1041
2022-12-13T20:09:15.499Z 544 AudioSystem ioConfigChanged() modifying unknown output! 5461
2022-12-13T20:09:15.498Z 545 nvaudio_hw num_sinks = 1 out->num_devices = 0 out->devices1[out->num_devices] = 1024
2022-12-13T20:09:15.498Z 545 nvaudio_hw nvaudio_dev_create_audio_patch(): handle generated, handle:146
2022-12-13T20:09:15.497Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.495Z 3700 chatty uid=1000(system) Binder:3700_15 identical 1 line
2022-12-13T20:09:15.495Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 4
2022-12-13T20:09:15.487Z 4080 AudioSystem ioConfigChanged() modifying unknown output! 1286
2022-12-13T20:09:15.487Z 3563 NvOsDebugPrintf NVMEDIA: FrameRate = 1000000
2022-12-13T20:09:15.485Z 544 AudioSystem ioConfigChanged() modifying unknown output! 1286
2022-12-13T20:09:15.485Z 546 DeviceMSDHAL createAudioPatch() patch:161 created between stream:1286 and BUS
2022-12-13T20:09:15.483Z 544 AudioFlinger AudioFlinger's thread 0x23679ea040 tid=21634 ready to run
2022-12-13T20:09:15.473Z 3700 system_server oneway function results will be dropped but finished with status OK and parcel size 8
2022-12-13T20:09:15.469Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:15.469Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:15.469Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:15.469Z 544 AudioFlinger HAL format 0xa000000 is not linear pcm
2022-12-13T20:09:15.469Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:15.469Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:15.466Z 546 msf_ddpenc_node ddpenc_node_reopen_encoder: ddp_enc set up in ddp:0, atmos_transcode:1
2022-12-13T20:09:15.465Z 29579 SurfaceUtils set up nativeWindow 0x30a587e220 for 1920x1080, color 0x146, rotation 0, usage 0x2900
2022-12-13T20:09:15.464Z 29579 ACodec Scaled Width is 1920, height is 1080
2022-12-13T20:09:15.464Z 29579 ACodec Get OMX_IndexConfigScale: 0x10000/0x10000
2022-12-13T20:09:15.464Z 546 msf_ddpenc_node ddpenc_node_open: call ddpenc_node_reopen_encoder, h_ddp_enc_lib_wrapper:0x41601290, h_ddp_enc_lib_wrapper:0x41601290
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf OPENMAX: HandleNewStreamFormat: 3609: Send OMX_EventPortSettingsChanged: nFrameWidth = 1920, nFrameHeight = 1080
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf
bitDepth ========================================> 8
NewStreamInfo->bValidMasteringDispData ==========> 0
bAllowClientValues ==============================> 0
NewStreamInfo->bValidContentLightLevelInfo ======> 0
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf Allocating new output: 1920x1088 (x 11), ThumbnailMode = 0
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 997: BeginSequence ColorPrimaries = 1, TransferCharacteristics = 1, MatrixCoefficients = 1
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 995: NumOfSurfaces = 11, InteraceStream = 0, InterlaceEnabled = 0, bSecure = 0, MVC = 0 Semiplanar = 1, bReinit = 1, BitDepthForSurface = 8 LumaBitDepth = 8, ChromaBitDepth = 8, ChromaFormat = 5
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 857: SurfaceLayout = 3
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence:738 ColorSpace = NvColorSpace_YCbCr709
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 727: ColorFormat : 5
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 631: Display Aspect Ratio : (1920x1080)
2022-12-13T20:09:15.463Z 546 msf_ddpenc_node ddpenc_node_reopen_encoder: ddp_enc set up in ddp:1, atmos_transcode:1
2022-12-13T20:09:15.463Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 630: Display Resolution : (1920x1080)
2022-12-13T20:09:15.459Z 544 AudioFlinger AudioFlinger's thread 0x25782bd8c0 tid=21633 ready to run
2022-12-13T20:09:15.459Z 546 msf_ddpenc_node ddpenc_node_open: call ddpenc_node_reopen_encoder, h_ddp_enc_lib_wrapper:0x416018d0, h_ddp_enc_lib_wrapper:0x416018d0
2022-12-13T20:09:15.458Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 551: DecodeBuffers = 5, pnvsi->eCodec = 0
2022-12-13T20:09:15.458Z 3563 NvOsDebugPrintf NVMEDIA: cbBeginSequence: 175: BeginSequence 1920x1088, bVPR = 0
2022-12-13T20:09:15.458Z 3563 NvOsDebugPrintf NVMEDIA: bEnableVideoSmootheningbEnable = 0
2022-12-13T20:09:15.458Z 546 StreamInMSDHAL initStream() requestOutput() format:0x4
2022-12-13T20:09:15.458Z 546 DeviceMSDHAL openInputStream() device:0x80100000, format:0xa000000, channels:0x17000c, samplerate:48000, flags:0x80
2022-12-13T20:09:15.458Z 3563 NvOsDebugPrintf NVMEDIA: RefreshRate = 59.940000
2022-12-13T20:09:15.457Z 544 AudioFlinger HAL output buffer size 24576 frames, normal sink buffer size 24576 frames
2022-12-13T20:09:15.455Z 546 DeviceMSDHAL Actual Audio Sink device 400
2022-12-13T20:09:15.454Z 545 modules.usbaudio.audio_hal Set use_msd_patch to 1
2022-12-13T20:09:15.453Z 545 nvaudio_hw use_msd_patch 1
2022-12-13T20:09:15.453Z 29579 PlaybackController setting mCurrentOptions audio stream index from null to 1
2022-12-13T20:09:15.452Z 29579 PlaybackController skipping setting audio stream, already set to requested index 1
2022-12-13T20:09:15.452Z 29579 PlaybackController trying to switch audio stream from 1 to 1
2022-12-13T20:09:15.452Z 29579 VideoManager re-retrieved libVLC audio track index 1
2022-12-13T20:09:15.452Z 29579 PlaybackController Setting subtitle index to: -1
2022-12-13T20:09:15.451Z 29579 PlaybackController Turning off subs
2022-12-13T20:09:15.451Z 29579 PlaybackController Play method: Direct
2022-12-13T20:09:15.451Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Sessions/Playing/Progress
2022-12-13T20:09:15.451Z 544 APM_AudioPolicyManager setMsdEnableInt:mMsdEnable:1, enable:1, mIsDapOn:1, mIsGamingAppActive:0, isYoutubeActiveOnA2DP:0, isNonMsdHalAudioActive:0
2022-12-13T20:09:15.450Z 29579 VideoManager re-retrieved libVLC audio track index 1
2022-12-13T20:09:15.448Z 29579 VLC [00000030258cf130/5466] libvlc decoder: failed to create audio output
2022-12-13T20:09:15.448Z 29579 VLC [00000030158a5600/5466] libvlc audio output: too low audio sample frequency (0)
2022-12-13T20:09:15.448Z 3556 ResourceManagerService Ignoring request to add new resource entry with value <= 0
2022-12-13T20:09:15.447Z 29579 SurfaceUtils set up nativeWindow 0x30a587e220 for 1920x1080, color 0x106, rotation 0, usage 0x2900
2022-12-13T20:09:15.440Z 29579 ACodec Scaled Width is 1920, height is 1080
2022-12-13T20:09:15.440Z 29579 ACodec Get OMX_IndexConfigScale: 0x10000/0x10000
2022-12-13T20:09:15.440Z 3563 OMXNodeInstance getConfig(0x4b1c0630:Nvidia.h264.decode, ConfigAndroidVendorExtension(0x6f100004)) ERROR: UnsupportedIndex(0x8000101a)
2022-12-13T20:09:15.440Z 29579 ACodec Enable timestamp filtering for Video Decoder
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf WARNING! This media may play as non HDR
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf WARNING! HDRStatic info being set, even for non SMPTE 2084 case
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf NvMMLiteBlockCreate : Block : BlockType = 261
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf NVMEDIA: RefreshRate = 59.940000
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf NVMEDIA: DisplayWidth = 3840, DisplayHeight = 2160
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf NVMEDIA: Reading vendor.tegra.display-size : status: 0
2022-12-13T20:09:15.439Z 3563 NvOsDebugPrintf NVMEDIA: RefreshRate = 59.940000
2022-12-13T20:09:15.438Z 3563 NvOsDebugPrintf NVMEDIA: NvMMLiteTVMRDecBlockOpen: 3532: NvMMLiteBlockOpen
2022-12-13T20:09:15.438Z 3563 NvOsDebugPrintf NvMMLiteOpen : Block : BlockType = 261
2022-12-13T20:09:15.437Z 3563 OMXNodeInstance getParameter(0x4b1c0630:Nvidia.h264.decode, ??(0x6f600011)) ERROR: NotImplemented(0x80001006)
2022-12-13T20:09:15.437Z 3563 NvOsDebugPrintf NVX_IndexParamAllocateNativeHandle : Using Native Handles
2022-12-13T20:09:15.436Z 29579 SurfaceUtils connecting to surface 0x30a587e220, reason connectToSurface(reconnect)
2022-12-13T20:09:15.435Z 29579 SurfaceUtils disconnecting from surface 0x30a587e220, reason connectToSurface(reconnect)
2022-12-13T20:09:15.435Z 29579 MediaCodec [OMX.Nvidia.h264.decode] setting surface generation to 30288898
2022-12-13T20:09:15.435Z 29579 SurfaceUtils connecting to surface 0x30a587e220, reason connectToSurface
2022-12-13T20:09:15.431Z 29579 NdkMediaCodec org.jellyfin.androidtv
2022-12-13T20:09:15.429Z 3563 OMXMaster makeComponentInstance(OMX.Nvidia.h264.decode) in android.hardwar process
2022-12-13T20:09:15.429Z 29579 OMXClient IOmx service obtained
2022-12-13T20:09:15.422Z 29579 VideoManager Surface sized 1920 x 1080
2022-12-13T20:09:15.422Z 29579 VLC [0000002fe594d1c0/5476] libvlc window: request 1 not implemented
2022-12-13T20:09:15.421Z 29579 VLC [0000002fe594d1c0/5476] libvlc window: request 0 not implemented
2022-12-13T20:09:15.421Z 29579 VLC [00000030158a1dc0/5476] libvlc vout display: Could not initialize NativeWindow Priv API.
2022-12-13T20:09:15.421Z 29579 VLC [0000002fe594d1c0/5476] libvlc window: request 1 not implemented
2022-12-13T20:09:15.412Z 29579 VLC [00000030258c8560/5466] libvlc decoder: Exception occurred in MediaCodecInfo.getCapabilitiesForType
2022-12-13T20:09:15.303Z 29579 VLC [0000002ff5880c60/5475] http stream: local stream 9 error: Cancellation (0x8)
2022-12-13T20:09:15.161Z 29579 VLC [0000002ff5880c60/5475] http stream: local stream 5 error: Cancellation (0x8)
2022-12-13T20:09:14.888Z 29579 VLC [0000002ff5880c60/5473] http stream: local stream 1 error: Stream closed (0x5)
2022-12-13T20:09:14.886Z 29579 chatty uid=10130(org.jellyfin.androidtv) VlcObject identical 7 lines
2022-12-13T20:09:14.859Z 29579 VLC [0000002ff5880c60/5473] http stream: local stream 1 error: Stream closed (0x5)
2022-12-13T20:09:14.852Z 29579 VLC [0000002ff5880c60/5475] http stream: local stream 1 error: Cancellation (0x8)
2022-12-13T20:09:14.468Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:14.467Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:14.466Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:14.421Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:14.421Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:14.421Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:14.421Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:14.421Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:13.419Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:13.419Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:13.418Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:13.381Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:13.381Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:13.381Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:13.381Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:13.381Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:12.851Z 3889 nv_bt_stats btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2022-12-13T20:09:12.773Z 3371 NvOsDebugPrintf NvRmStreamFree: WARN: pStream is NULL
2022-12-13T20:09:12.771Z 3371 hdcp up tsec_version: 1
2022-12-13T20:09:12.379Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:12.379Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:12.378Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:12.349Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:12.349Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:12.349Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:12.349Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:12.348Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:11.514Z 3354 hdcp up SecureHdcp_GetCurrentLevel: cur: 0x04, max: 0x04
2022-12-13T20:09:11.347Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:11.347Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:11.346Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:11.309Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:11.309Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:11.309Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:11.308Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:11.308Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:10.307Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:10.307Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:10.306Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:10.274Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:10.274Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:10.274Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:10.274Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:10.274Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:09.273Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:09.272Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:09.271Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:09.241Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:09.241Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:09.241Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:09.241Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:09.241Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:09.232Z 3700 WindowManager handleComboKeys keyCode: 4, keyAction: 1
2022-12-13T20:09:08.957Z 3700 WindowManager handleComboKeys keyCode: 4, keyAction: 0
2022-12-13T20:09:08.848Z 3889 nv_bt_stats btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2022-12-13T20:09:08.239Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:08.239Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:08.238Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:08.209Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:08.209Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:08.209Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:08.209Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:08.209Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:07.767Z 3371 NvOsDebugPrintf NvRmStreamFree: WARN: pStream is NULL
2022-12-13T20:09:07.766Z 3371 hdcp up tsec_version: 1
2022-12-13T20:09:07.207Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:07.207Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:07.206Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:07.165Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:07.165Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:07.165Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:07.165Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:07.164Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:06.263Z 3700 TaskPersister File error accessing recents directory (directory doesn't exist?).
2022-12-13T20:09:06.163Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:06.163Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:06.162Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:06.125Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:06.125Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:06.125Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:06.125Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:06.125Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:05.123Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:05.123Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:05.122Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:05.085Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:05.085Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:05.084Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:05.084Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:05.084Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:04.846Z 3889 nv_bt_stats btif_nv_stats_update_host_stats() addr: ff:ff:ff:ff:ff:ff , json string: cmd_timeout_rsp_after_to
2022-12-13T20:09:04.083Z 21074 adbd UsbFfs-monitor thread spawned
2022-12-13T20:09:04.083Z 21074 adbd UsbFfsConnection constructed
2022-12-13T20:09:04.082Z 21074 adbd opening control endpoint /dev/usb-ffs/adb/ep0
2022-12-13T20:09:04.045Z 21074 adbd UsbFfsConnection being destroyed
2022-12-13T20:09:04.045Z 21074 adbd destroying transport UsbFfs
2022-12-13T20:09:04.045Z 21074 adbd UsbFfs: already offline
2022-12-13T20:09:04.045Z 21074 adbd UsbFfs: connection terminated: monitor thread finished
2022-12-13T20:09:04.045Z 21074 adbd timed out while waiting for FUNCTIONFS_BIND, trying again
2022-12-13T20:09:03.822Z 3377 powerHAL::common Power hint:0x8 is not supported in PowerHAL
2022-12-13T20:09:03.819Z 3700 android_os_HwBinder HwBinder: Starting thread pool for getting: vendor.nvidia.hardware.graphics.display@1.0::INvDisplay/default
2022-12-13T20:09:03.818Z 3700 AudioSystem-JNI Command failed for android_media_AudioSystem_setParameters: -22
2022-12-13T20:09:03.817Z 3700 NvUtils onActivityStopped AM packageName = org.jellyfin.androidtv
2022-12-13T20:09:03.808Z 29579 ACRA onActivitySaveInstanceState class org.jellyfin.androidtv.ui.browsing.MainActivity
2022-12-13T20:09:03.807Z 29579 ACRA onActivityStopped class org.jellyfin.androidtv.ui.browsing.MainActivity
2022-12-13T20:09:03.803Z 29579 Surface freeAllBuffers: 1 buffers were freed while being dequeued!
2022-12-13T20:09:03.630Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Sessions/Playing
2022-12-13T20:09:03.571Z 29579 skia --- Failed to create image decoder with message 'unimplemented'
2022-12-13T20:09:03.570Z 29579 chatty uid=10130(org.jellyfin.androidtv) glide-source-th identical 4 lines
2022-12-13T20:09:03.568Z 29579 skia --- Failed to create image decoder with message 'unimplemented'
2022-12-13T20:09:03.503Z 29579 ReportingHelper Playback of Recentering started.
2022-12-13T20:09:03.503Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Sessions/Playing
2022-12-13T20:09:03.502Z 29579 VideoManager Setting video index to: 0
2022-12-13T20:09:03.502Z 29579 VideoManager Video path set to: https://WEBSITE_NAME.COM/Videos/85b5c912-2e4b-6302-feab-723f9c33a0b4/stream.mkv?api_key=API_KEY&DeviceId=DEVICE_ID&MediaSourceId=85b5c9122e4b6302feab723f9c33a0b4&Tag=f067ca1a9ae0578352d8287f8a1debd6&Static=true
2022-12-13T20:09:03.500Z 29579 ViewTarget
2022-12-13T20:09:03.494Z 29579 VideoManager Setting playback speed: 1.000000
2022-12-13T20:09:03.493Z 29579 PlaybackController Playing back in VLC.
2022-12-13T20:09:03.493Z 29579 PlaybackController default sub index set to -1 remote default -1
2022-12-13T20:09:03.493Z 29579 PlaybackController default audio index set to 1 remote default 1
2022-12-13T20:09:03.492Z 29579 PlaybackController default audio index set to 1
2022-12-13T20:09:03.492Z 29579 VideoManager Surface attached
2022-12-13T20:09:03.490Z 29579 VideoManager Network buffer set to 600
2022-12-13T20:09:03.484Z 29579 PlaybackController Preferring VLC
2022-12-13T20:09:03.484Z 29579 PlaybackController User preferred player is: VLC
2022-12-13T20:09:03.483Z 29579 PlaybackController Explicit deinterlacing will NOT be used
2022-12-13T20:09:03.483Z 29579 PlaybackController Internal player would direct stream
2022-12-13T20:09:03.480Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Items/85b5c912-2e4b-6302-feab-723f9c33a0b4/PlaybackInfo?format=json
2022-12-13T20:09:03.418Z 6855 DeviceUnlockedTag DeviceUnlockedTag.notifyDeviceLockStatusChanged():38 Notify device unlocked.
2022-12-13T20:09:03.418Z 6855 GoogleInputMethodService GoogleInputMethodService.onStartInput():2045
2022-12-13T20:09:03.417Z 6855 GoogleInputMethodService GoogleInputMethodService.onFinishInput():3466
2022-12-13T20:09:03.400Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Items/85b5c912-2e4b-6302-feab-723f9c33a0b4/PlaybackInfo?format=json
2022-12-13T20:09:03.398Z 29579 PlaybackController VLC would direct stream
2022-12-13T20:09:03.396Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Items/85b5c912-2e4b-6302-feab-723f9c33a0b4/PlaybackInfo?format=json
2022-12-13T20:09:03.387Z 3700 ActivityTaskManager Displayed org.jellyfin.androidtv/.ui.playback.PlaybackOverlayActivity: +165ms
2022-12-13T20:09:03.343Z 29579 libc Access denied finding property "ro.vendor.sys.NV_FPSDYNAMIC"
2022-12-13T20:09:03.343Z 29579 libc Access denied finding property "ro.vendor.sys.NV_PROCESS_NAME"
2022-12-13T20:09:03.343Z 29579 libc Access denied finding property "ro.vendor.sys.NV_LOGFPS"
2022-12-13T20:09:03.343Z 29579 libc Access denied finding property "ro.vendor.sys.NV_ASYNC"
2022-12-13T20:09:03.324Z 3700 AudioSystem-JNI Command failed for android_media_AudioSystem_setParameters: -22
2022-12-13T20:09:03.324Z 3700 chatty uid=1000(system) Binder:3700_D identical 2 lines
2022-12-13T20:09:03.323Z 3700 AudioSystem-JNI Command failed for android_media_AudioSystem_setParameters: -22
2022-12-13T20:09:03.322Z 3700 NvUtils onActivityResumed AM packageName = org.jellyfin.androidtv
2022-12-13T20:09:03.317Z 29579 android_os_HwBinder HwBinder: Starting thread pool for getting: vendor.nvidia.hardware.cpl.service_common@1.0::INvCplHalCommonService/default
2022-12-13T20:09:03.314Z 3700 MediaFocusControl requestAudioFocus() from uid/pid 10130/29579 clientId=android.media.AudioManager@3ee543aorg.jellyfin.androidtv.ui.playback.CustomPlaybackOverlayFragment$6@8ffb8eb callingPack=org.jellyfin.androidtv req=1 flags=0x0 sdk=33
2022-12-13T20:09:03.312Z 29579 AudioManager See the documentation of requestAudioFocus() for what to use instead with android.media.AudioAttributes to qualify your playback use case
2022-12-13T20:09:03.312Z 29579 AudioManager Use of stream types is deprecated for operations other than volume control
2022-12-13T20:09:03.312Z 29579 ACRA onActivityResumed class org.jellyfin.androidtv.ui.playback.PlaybackOverlayActivity
2022-12-13T20:09:03.309Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Items/85b5c912-2e4b-6302-feab-723f9c33a0b4/PlaybackInfo?format=json
2022-12-13T20:09:03.308Z 29579 PlaybackController *** Using extended Exoplayer profile options
2022-12-13T20:09:03.306Z 29579 PlaybackController Max bitrate is: 120000000
2022-12-13T20:09:03.306Z 29579 PlaybackController Play called from state: IDLE with pos: 0 and sub index: null
2022-12-13T20:09:03.292Z 29579 DefaultRenderersFactory Loaded FfmpegAudioRenderer.
2022-12-13T20:09:03.289Z 29579 ExoPlayerImpl Init 2951188 [ExoPlayerLib/2.18.2] [mdarcy, SHIELD Android TV, NVIDIA, 30]
2022-12-13T20:09:03.261Z 29579 android_os_HwBinder HwBinder: Starting thread pool for getting: vendor.nvidia.hardware.cpl.service_common@1.0::INvCplHalCommonService/default
2022-12-13T20:09:03.250Z 29579 ACRA onActivityStarted class org.jellyfin.androidtv.ui.playback.PlaybackOverlayActivity
2022-12-13T20:09:03.246Z 29579 ActivityThemeExtensionsKt Applying theme DARK
2022-12-13T20:09:03.242Z 4636 android_os_HwBinder HwBinder: Starting thread pool for getting: vendor.nvidia.hardware.power@1.0::IPower/default
2022-12-13T20:09:03.241Z 29579 ACRA onActivityCreated class org.jellyfin.androidtv.ui.playback.PlaybackOverlayActivity
2022-12-13T20:09:03.238Z 3700 NvAppProfileService App Profiles: Enabled
2022-12-13T20:09:03.228Z 3700 ActivityTaskManager Tried to set launchTime (0) < mLastActivityLaunchTime (2122957492)
2022-12-13T20:09:03.227Z 29579 ACRA onActivityPaused class org.jellyfin.androidtv.ui.browsing.MainActivity
2022-12-13T20:09:03.226Z 3700 EventSequenceValidator dec AccIntentStartedEvents to 1
2022-12-13T20:09:03.226Z 29579 NavigationRepositoryImpl$navigate$action Navigating to nothing
2022-12-13T20:09:03.223Z 3377 powerHAL::common Power hint:0x8 is not supported in PowerHAL
2022-12-13T20:09:03.221Z 3700 EventSequenceValidator inc AccIntentStartedEvents to 2
2022-12-13T20:09:03.221Z 3700 ActivityTaskManager START u0 {cmp=org.jellyfin.androidtv/.ui.playback.PlaybackOverlayActivity (has extras)} from uid 10130
2022-12-13T20:09:03.220Z 29579 NavigationRepositoryImpl Navigating to Activity(activity=class org.jellyfin.androidtv.ui.playback.PlaybackOverlayActivity (Kotlin reflection is not available), extras=Bundle[{Position=0}])
2022-12-13T20:09:03.202Z 29579 jellyfin-apiclient Response received from: https://WEBSITE_NAME.COM/Shows/cff26d4f-14e4-bf99-fb4d-e69f4b3fe723/Episodes?Fields=MediaSources%2CMediaStreams%2CPath%2CChapters%2COverview%2CPrimaryImageAspectRatio%2CChildCount&UserId=5f9e0569-c915-4158-8d16-8ec09bc1bf45&SeasonId=623c5398-5a0f-7545-631e-30a4a90c98e3&IsMissing=false&IsVirtualUnaired=false&format=json
2022-12-13T20:09:03.120Z 29579 jellyfin-apiclient Adding request to queue: https://WEBSITE_NAME.COM/Shows/cff26d4f-14e4-bf99-fb4d-e69f4b3fe723/Episodes?Fields=MediaSources%2CMediaStreams%2CPath%2CChapters%2COverview%2CPrimaryImageAspectRatio%2CChildCount&UserId=5f9e0569-c915-4158-8d16-8ec09bc1bf45&SeasonId=623c5398-5a0f-7545-631e-30a4a90c98e3&IsMissing=false&IsVirtualUnaired=false&format=json
2022-12-13T20:09:03.107Z 3700 WindowManager handleComboKeys keyCode: 23, keyAction: 1
This issue has gone 120 days without comment. To avoid abandoned issues, it will be closed in 21 days if there are no new comments.
If you're the original submitter of this issue, please comment confirming if this issue still affects you in the latest release or master branch, or close the issue if it has been fixed. If you're another user also affected by this bug, please comment confirming so. Either action will remove the stale label.
This bot exists to prevent issues from becoming stale and forgotten. Jellyfin is always moving forward, and bugs are often fixed as side effects of other changes. We therefore ask that bug report authors remain vigilant about their issues to ensure they are closed if fixed, or re-confirmed - perhaps with fresh logs or reproduction examples - regularly. If you have any questions you can reach us on Matrix or Social Media.
I am also have this issue of slow loading when using libVlc
Describe the bug
This is compared to Exoplayer playing back near instantaneously. I'm not on the local network of my server. My latency to the server is about 65ms. However network throughput is excellent, and even on 80-90 Mbps files playback is fine with no buffering. This seems unrelated to latency or network throughput given the issue doesn't exhibit itself with Exoplayer. This is direct playing, no transcoding. Persists on app reinstall fresh from the play store.
Logs
This isn't a crash and as far as I cant tell there's nothing in the server logs to indicate an issue there. Still, here are the time stamps that seem to be associated with the server processing the request. Removed info since I'm not sure how much of this is sensitive. The last line is 20 seconds after the start, since I didn't observe that big of a delay I don't believe it to be related to the issue.
FFmpeg logs
Media info of the file
Application version
0.15.0-beta.3
Device information
Nvidia Shield Pro 2019
Android version
Android 11, fully up to date as of posting
Jellyfin server version
10.8.8 x64 Linux