Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Issue]: 4k DV HDR10 HEVC black screen during playback pixel 6a #1371

Open
1 of 3 tasks
jasonafriedman opened this issue May 1, 2024 · 6 comments
Open
1 of 3 tasks
Labels
bug Something isn't working

Comments

@jasonafriedman
Copy link

jasonafriedman commented May 1, 2024

Describe the bug

When playing Dolby Vision content on pixel 6a, the app reports that it is direct playing, however there is just a black screen for video. The audio works fine. If DV is not supported on the pixel 6a, then the app should not allow a broken direct play and force server transcoding. If I select a lower bitrate (eg1080p 10mbps) then I can get the video to show up, however it is green and purple (Jellyfin 10.8 AMD VAAPI transcoding does not support tonemapping on my vega 7 gpu).

  • Server OS: Truenas Scale Dragonfish-24.04.0
  • Linux Kernel: 6.6.20
  • Virtualization: Kubernetes
  • Clients: Android Pixel 6a/ android 14 with Jellyfin android app v2.6.1
  • Browser: no
  • FFmpeg Version: 5.1.4
  • Playback Method: DirectPlay
  • Hardware Acceleration: VAAPI
  • GPU Model: Ryzen 5650g pro with Radeon Vega 7 graphics
  • Plugins: AudioDB, MusicBrainz, OMDb, Playback Reporting, Reports, Studio Images, TMDb
  • Reverse Proxy: Traefik , however connected through lan though blocky split dns
  • Base URL: 192.168.0.15:8096
  • Networking: Ubiquiti edgerouter 4 connected to Ubiquiti U6-Pro
  • Storage: 4x 14tb HDD, 2x2tb SSD for transcode directory

Media info:
The Hobbit An Unexpected Journey (2012) [imdbid-tt0903624] - Extended Cut [Remux-2160p][DV HDR10][TrueHD Atmos 7.1][HEVC]-FraMeSToR
Containermkv
Path/media/library/movies/The Hobbit - An Unexpected Journey (2012)/The Hobbit An Unexpected Journey (2012) [imdbid-tt0903624] - Extended Cut [Remux-2160p][DV HDR10][TrueHD Atmos 7.1][HEVC]-FraMeSToR.mkv
Size71112 MB
Video
Title4K HEVC HDR
CodecHEVC
ProfileMain 10
Level153
Resolution3840x2160
Aspect ratio16:9
InterlacedNo
Framerate23.976025
Bitrate54472 kbps
Bit depth10 bit
Video rangeHDR
Video range typeHDR10
DV titleDV Profile 7.6
DV version major1
DV version minor0
DV profile7
DV level6
DV rpu preset flag1
DV el preset flag1
DV bl preset flag1
DV bl signal compatibility id6
Color spacebt2020nc
Color transfersmpte2084
Color primariesbt2020
Pixel formatyuv420p10le
Ref frames1
Audio
TitleTrueHD Atmos 7.1 - Eng - Default
Languageeng
CodecTRUEHD
Layout7.1
Channels8 ch
Bitrate3402 kbps
Sample rate48000 Hz
Bit depth24 bit
DefaultYes
ForcedNo
ExternalNo
Audio
TitleAC-3 5.1-EX - Eng - Dolby Digital
Languageeng
CodecAC3
Layout5.1
Channels6 ch
Bitrate448 kbps
Sample rate48000 Hz
DefaultNo
ForcedNo
ExternalNo
Audio
TitleCommentary by director Peter Jackson and writer Philippa Boyens - Eng - Dolby Digital - Stereo
Languageeng
CodecAC3
Layoutstereo
Channels2 ch
Bitrate192 kbps
Sample rate48000 Hz
DefaultNo
ForcedNo
ExternalNo
Subtitle
TitleEnglish (SDH) - PGSSUB
Languageeng
CodecPGSSUB
DefaultNo
ForcedNo
ExternalNo
Subtitle
TitleArabic - PGSSUB
Languageara
CodecPGSSUB
DefaultNo
ForcedNo
ExternalNo
Subtitle
TitleChinese (Simplified) - PGSSUB
Languagechi
CodecPGSSUB
DefaultNo
ForcedNo
ExternalNo
Subtitle
TitleChinese (Traditional) - PGSSUB
Languagechi
CodecPGSSUB
DefaultNo
ForcedNo
ExternalNo
Subtitle

Logs

05-01 15:17:21.621  1869  1874 I pixel-thermal: neutral_therm:27.291 raw data: neutral_therm:27291
05-01 15:17:21.622  1869  1874 I pixel-thermal: Power rails total power: 277.09 mW for 60073 ms
05-01 15:17:21.622  1869  1874 I pixel-thermal: Power rails [S1M_VDD_MIF: 16.88 mW] [S5M_VDD_INT: 25.84 mW] [S4M_VDD_CPUCL0: 20.45 mW] [S3M_VDD_CPUCL1: 9.09 mW] [S2M_VDD_CPUCL2: 10.26 mW] [L15M_VDD_SLC_M: 5.74 mW]
05-01 15:17:21.622  1869  1874 I pixel-thermal: Power rails [VSYS_PWR_DISPLAY: 122.26 mW] [L2S_VDD_AOC_RET: 3.73 mW] [S10M_VDD_TPU: 0.83 mW] [L8S_UFS_VCCQ: 3.72 mW] [S9S_VDD_AOC: 18.33 mW] [S4S_VDD2H_MEM: 19.44 mW]
05-01 15:17:21.622  1869  1874 I pixel-thermal: Power rails [S2S_VDD_G3D: 0.50 mW] [S5S_VDDQ_MEM: 2.48 mW] [S10S_VDD2L: 16.86 mW] [L9S_GNSS_CORE: 0.68 mW]
05-01 15:17:24.229  1171  1171 I WifiHAL : Creating message to get link statistics; iface = 47
05-01 15:17:24.302  1171  1171 I WifiHAL : In GetLinkStatsCommand::handleResponse
05-01 15:17:24.398  1579  2080 D ConnectivityService: NetReassign [no changes] [c 6] [a 9] [i 21]
05-01 15:17:25.902  2778 31742 D IKE     : new IkeAlarmWithListener for IkeAlarmReceiver.ACTION_DPD
05-01 15:17:27.364  1171  1171 I WifiHAL : Creating message to get link statistics; iface = 47
05-01 15:17:27.427  1171  1171 I WifiHAL : In GetLinkStatsCommand::handleResponse
05-01 15:17:27.503  1579  2080 D ConnectivityService: NetReassign [no changes] [c 6] [a 4] [i 15]
05-01 15:17:29.578  2291  2328 I deeptouch: I0000 00:00:1714598249.577085    2328 tf_lite_classifier.cc:413] Event stream classified as kNone
05-01 15:17:29.601  9166  9166 I ExoPlayerImpl: Init 264e62b [ExoPlayerLib/2.19.1] [bluejay, Pixel 6a, Google, 34]
05-01 15:17:29.605  9166  9166 I DefaultRenderersFactory: Loaded FfmpegAudioRenderer.
05-01 15:17:29.610  9166  9166 D EventLogger: audioAttributes [eventTime=0.01, mediaPos=0.00, window=0, 3,0,1,1]
05-01 15:17:29.663  1137  1737 D usf_sensor_hal: accelerometer: Enter Flush
05-01 15:17:29.667  9166  9166 D EventLogger: surfaceSize [eventTime=0.07, mediaPos=0.00, window=0, 0, 0]
05-01 15:17:29.680  9166  9166 D EventLogger: surfaceSize [eventTime=0.08, mediaPos=0.00, window=0, 1080, 2215]
05-01 15:17:29.719  9166  9166 D EventLogger: timeline [eventTime=0.12, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
05-01 15:17:29.719  9166  9166 D EventLogger:   period [?]
05-01 15:17:29.719  9166  9166 D EventLogger:   window [?, seekable=false, dynamic=true]
05-01 15:17:29.719  9166  9166 D EventLogger: ]
05-01 15:17:29.720  9166  9166 D EventLogger: mediaItem [eventTime=0.12, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
05-01 15:17:29.721  1579  2272 I MediaSessionStack: addSession to bottom of stack | record: org.jellyfin.mobile/PlayerViewModel (userId=0)
05-01 15:17:29.722  1579  2272 D MediaSessionService: Media button session is changed to org.jellyfin.mobile/PlayerViewModel (userId=0)
05-01 15:17:29.723  1579  2272 I MediaSessionStack: onPlaybackStateChanged - Pushing session to top | record: org.jellyfin.mobile/PlayerViewModel (userId=0)
05-01 15:17:29.725  9166  9166 D EventLogger: state [eventTime=0.12, mediaPos=0.00, window=0, BUFFERING]
05-01 15:17:29.725  9166  9166 D EventLogger: positionDiscontinuity [eventTime=0.12, mediaPos=6957.78, window=0, reason=SEEK, PositionInfo:old [mediaItem=0, period=0, pos=0], PositionInfo:new [mediaItem=0, period=0, pos=6957783]]
05-01 15:17:29.725  1579  2760 I MediaFocusControl: requestAudioFocus() from uid/pid 10271/9166 AA=USAGE_MEDIA/CONTENT_TYPE_MOVIE [email protected]@ff53114 callingPack=org.jellyfin.mobile req=1 flags=0x0 sdk=34
05-01 15:17:29.726  1579  2760 I AS.FadeOutManager: unfadeOutUid() uid:10271
05-01 15:17:29.728  9166  9166 D EventLogger: playWhenReady [eventTime=0.13, mediaPos=6957.78, window=0, true, USER_REQUEST]
05-01 15:17:29.733  9166  9166 D EventLogger: timeline [eventTime=0.13, mediaPos=6957.78, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
05-01 15:17:29.733  9166  9166 D EventLogger:   period [?]
05-01 15:17:29.733  9166  9166 D EventLogger:   window [?, seekable=false, dynamic=false]
05-01 15:17:29.733  9166  9166 D EventLogger: ]
05-01 15:17:29.733  9166  9166 D EventLogger: loading [eventTime=0.13, mediaPos=6957.78, window=0, period=0, true]
05-01 15:17:29.921  4053  4053 I bw_access: Too many child iterations.
05-01 15:17:30.146  1579  2760 W ProcessStats: Tracking association SourceState{aec4ad2 com.android.settings/1000 Service #157487} whose proc state 8 is better than process ProcessState{6737c69 com.google.android.apps.wellbeing/10139 pkg=com.google.android.apps.wellbeing} proc state 15 (1 skipped)
05-01 15:17:30.282  9166  9166 D EventLogger: timeline [eventTime=0.68, mediaPos=6957.78, window=0, period=0, periodCount=1, windowCount=1, reason=SOURCE_UPDATE
05-01 15:17:30.282  9166  9166 D EventLogger:   period [10951.06]
05-01 15:17:30.282  9166  9166 D EventLogger:   window [10951.06, seekable=true, dynamic=false]
05-01 15:17:30.282  9166  9166 D EventLogger: ]
05-01 15:17:30.291  9166  9166 D EventLogger: audioEnabled [eventTime=0.69, mediaPos=6957.78, window=0, period=0]
05-01 15:17:30.292  9166  9166 D EventLogger: tracks [eventTime=0.69, mediaPos=6957.78, window=0, period=0
05-01 15:17:30.292  9166  9166 D EventLogger:   group [
05-01 15:17:30.292  9166  9166 D EventLogger:     [ ] Track:0, id=1, mimeType=video/dolby-vision, codecs=dvhe.07.06, res=3840x2160, language=und, selectionFlags=[default], supported=NO_UNSUPPORTED_TYPE
05-01 15:17:30.292  9166  9166 D EventLogger:   ]
05-01 15:17:30.292  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [X] Track:0, id=2, mimeType=audio/true-hd, channels=8, sample_rate=48000, language=en, label=TrueHD Atmos 7.1, selectionFlags=[default], supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=3, mimeType=audio/ac3, channels=6, sample_rate=48000, language=en, label=AC-3 5.1-EX, supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=4, mimeType=audio/ac3, channels=2, sample_rate=48000, language=en, label=Commentary by director Peter Jackson and writer Philippa Boyens, supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=5, mimeType=application/pgs, language=en, label=English (SDH), supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=6, mimeType=application/pgs, language=ar, label=Arabic, supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=7, mimeType=application/pgs, language=zh, label=Chinese (Simplified), supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=8, mimeType=application/pgs, language=zh, label=Chinese (Traditional), supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=9, mimeType=application/pgs, language=hbs-hrv, label=Croatian, supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.293  9166  9166 D EventLogger:     [ ] Track:0, id=10, mimeType=application/pgs, language=cs, label=Czech, supported=YES
05-01 15:17:30.293  9166  9166 D EventLogger:   ]
05-01 15:17:30.293  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=11, mimeType=application/pgs, language=da, label=Danish, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=12, mimeType=application/pgs, language=nl, label=Dutch, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=13, mimeType=application/pgs, language=et, label=Estonian, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=14, mimeType=application/pgs, language=fi, label=Finnish, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=15, mimeType=application/pgs, language=fr, label=French, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=16, mimeType=application/pgs, language=de, label=German (SDH), supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=17, mimeType=application/pgs, language=el, label=Greek, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=18, mimeType=application/pgs, language=he, label=Hebrew, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=19, mimeType=application/pgs, language=hu, label=Hungarian, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=20, mimeType=application/pgs, language=is, label=Icelandic, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=21, mimeType=application/pgs, language=it, label=Italian (SDH), supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=22, mimeType=application/pgs, language=ja, label=Japanese, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.294  9166  9166 D EventLogger:   group [
05-01 15:17:30.294  9166  9166 D EventLogger:     [ ] Track:0, id=23, mimeType=application/pgs, language=ko, label=Korean, supported=YES
05-01 15:17:30.294  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=24, mimeType=application/pgs, language=no, label=Norwegian, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=25, mimeType=application/pgs, language=pl, label=Polish, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=26, mimeType=application/pgs, language=pt, label=Portuguese (Brazilian), supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=27, mimeType=application/pgs, language=pt, label=Portuguese (Iberian), supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=28, mimeType=application/pgs, language=ro, label=Romanian, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=29, mimeType=application/pgs, language=ru, label=Russian, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=30, mimeType=application/pgs, language=hbs-srp, label=Serbian, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=31, mimeType=application/pgs, language=es, label=Spanish (Castilian), supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=32, mimeType=application/pgs, language=es, label=Spanish (Latin American), supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=33, mimeType=application/pgs, language=sv, label=Swedish, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=34, mimeType=application/pgs, language=th, label=Thai, supported=YES
05-01 15:17:30.295  9166  9166 D EventLogger:   ]
05-01 15:17:30.295  9166  9166 D EventLogger:   group [
05-01 15:17:30.295  9166  9166 D EventLogger:     [ ] Track:0, id=35, mimeType=application/pgs, language=tr, label=Turkish, supported=YES
05-01 15:17:30.296  9166  9166 D EventLogger:   ]
05-01 15:17:30.296  9166  9166 D EventLogger:   group [
05-01 15:17:30.296  9166  9166 D EventLogger:     [ ] Track:0, id=36, mimeType=application/pgs, language=fr, label=French (Commentary), supported=YES
05-01 15:17:30.296  9166  9166 D EventLogger:   ]
05-01 15:17:30.296  9166  9166 D EventLogger:   group [
05-01 15:17:30.296  9166  9166 D EventLogger:     [ ] Track:0, id=37, mimeType=application/pgs, language=de, label=German (Commentary), supported=YES
05-01 15:17:30.296  9166  9166 D EventLogger:   ]
05-01 15:17:30.296  9166  9166 D EventLogger:   group [
05-01 15:17:30.296  9166  9166 D EventLogger:     [ ] Track:0, id=38, mimeType=application/pgs, language=ja, label=Japanese (Commentary), supported=YES
05-01 15:17:30.296  9166  9166 D EventLogger:   ]
05-01 15:17:30.296  9166  9166 D EventLogger: ]
05-01 15:17:30.303  9166  9166 D EventLogger: downstreamFormat [eventTime=0.70, mediaPos=6957.78, window=0, period=0, id=2, mimeType=audio/true-hd, channels=8, sample_rate=48000, language=en, label=TrueHD Atmos 7.1, selectionFlags=[default]]
05-01 15:17:30.306  9166  9166 D EventLogger: audioDecoderInitialized [eventTime=0.71, mediaPos=6957.78, window=0, period=0, ffmpegLavc58.54.100-truehd]
05-01 15:17:30.306  9166  9166 D EventLogger: audioInputFormat [eventTime=0.71, mediaPos=6957.78, window=0, period=0, id=2, mimeType=audio/true-hd, channels=8, sample_rate=48000, language=en, label=TrueHD Atmos 7.1, selectionFlags=[default]]
05-01 15:17:30.393  1174  2095 D AudioFlinger: Client defaulted notificationFrames to 7696 for frameCount 15392
05-01 15:17:30.474  1171  1171 I WifiHAL : Creating message to get link statistics; iface = 47
05-01 15:17:30.485  1171  1171 I WifiHAL : In GetLinkStatsCommand::handleResponse
05-01 15:17:30.507  2778  2866 D IwlanDataService: onCapabilitiesChanged: 121 [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED LinkUpBandwidth>=12136Kbps LinkDnBandwidth>=196944Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.4.59, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 6, RSSI: -51, Link speed: 907Mbps, Tx Link speed: 907Mbps, Max Supported Tx Link speed: 1200Mbps, Rx Link speed: 960Mbps, Max Supported Rx Link speed: 1200Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: 1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>> SignalStrength: -51 AdminUids: [1000] SSID: "Friedman upstairs 2G" UnderlyingNetworks: Null]
05-01 15:17:30.507  2778  2866 D IwlanDataService: Network 121 connected using transport WIFI
05-01 15:17:30.507  2778  2896 D IwlanNetworkService: onCapabilitiesChanged: 121
05-01 15:17:30.508  2497  3411 D SHANNON_RCS: 1903 [0][PROX][TELE] onCapabilitiesChanged, Network: 121, NetworkCapabilities: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED LinkUpBandwidth>=12136Kbps LinkDnBandwidth>=196944Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.4.59, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 6, RSSI: -51, Link speed: 907Mbps, Tx Link speed: 907Mbps, Max Supported Tx Link speed: 1200Mbps, Rx Link speed: 960Mbps, Max Supported Rx Link speed: 1200Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>> SignalStrength: -51 UnderlyingNetworks: Null] (DefaultNetworkCallback%onCapabilitiesChanged:76)
05-01 15:17:30.512 28591 28662 I BugleRcsEngine: handleMessage processing message:[NOTIFY_UPTIME_IGNORE_STATE_CHANGED] with [non-null]:RcsEngineImpl reference [CONTEXT log_prefix="RcsEngineImpl[DUAL_REG]:[9b80aa03-ec26]>Handler" thread_id=81 ]
05-01 15:17:30.512 28591 28654 I BugleRcsEngine: Connected state: [1], networkType: [WIFI] [CONTEXT thread_id=78 ]
05-01 15:17:30.512 24852 24909 D DeviceStateHelper: Wifi freq: 5180
05-01 15:17:30.514 24852 24923 I WifiSignalInfo: onCapabilitiesChanged: rssi:-51
05-01 15:17:30.515 28591 28662 W BugleRcsEngine: Cannot use CS.apk as it's battery optimized [CONTEXT thread_id=81 ]
05-01 15:17:30.518  1579  2451 D ActivityManager: sync unfroze 5875 com.google.android.apps.messaging for 7
05-01 15:17:30.522  5875  5875 W TimedProcessReaper: Memory state is: 125
05-01 15:17:30.522  5875  5875 D TimedProcessReaper: Attempted to reap process, but schedule tolerance was exceeded
05-01 15:17:30.522  5875  5875 D TimedProcessReaper: Rescheduling process reaping after pollDuration
05-01 15:17:30.525  1579  2080 D ConnectivityService: NetReassign [no changes] [c 1] [a 1] [i 3]
05-01 15:17:30.531 28591 28662 I BugleRcsEngine: Rcs is enabled from user settings: true [CONTEXT log_prefix="ProvisioningEngineDataRetriever" thread_id=81 ]
05-01 15:17:30.535  9166  9166 D MediaRouter: onRestoreRoute() : route=RouteInfo{ name=myChevrolet, description=Bluetooth audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO  groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
05-01 15:17:30.535  9166  9166 V MediaRouter: Selecting route: RouteInfo{ name=myChevrolet, description=Bluetooth audio, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO  groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO , presentationDisplay=null }
05-01 15:17:30.536  1174 20771 D AudioFlinger: moveEffectChain_ll: successful move of session 0 from srcThread 0xb400007186999fe8 to dstThread 0xb40000718682bfe8 (1 effects moved, 0 effects started)
05-01 15:17:30.536  3861  6033 I AiAiAdaptiveAudio: (REDACTED) updateState: enabledInSettings=%b, playbackState=%s
05-01 15:17:30.536  3861  6033 I AiAiAdaptiveAudio: stopMonitoring
05-01 15:17:30.536  3861  6033 I AiAiAdaptiveAudio: (REDACTED) Updating calibration state: %s -> %s
05-01 15:17:30.577  1100  1140 D audio_hw_patch: deep-buffer-playback routing:
05-01 15:17:30.578  1100  1140 D audio_hw_patch: dev: 0x2
05-01 15:17:30.582  1174 20771 D audioserver: logFgsApiBegin: FGS Logger Transaction failed, -129
05-01 15:17:30.583   482   482 I hwservicemanager: getTransport: Cannot find entry [email protected]::IEffectsFactory/default in either framework or device VINTF manifest.
05-01 15:17:30.588  1100  1141 I EffectsFactory: EffectCreate() create_effect
05-01 15:17:30.593  9166  9166 D EventLogger: state [eventTime=0.99, mediaPos=6957.79, window=0, period=0, READY]
05-01 15:17:30.593  9166  9166 D EventLogger: isPlaying [eventTime=0.99, mediaPos=6957.79, window=0, period=0, true]
05-01 15:17:30.594  9166  9166 D EventLogger: loading [eventTime=0.99, mediaPos=6957.79, window=0, period=0, false]
05-01 15:17:30.594  1579  2272 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:30.594  1100  5370 D audio_hw_soundtrigger: updated Stream 3 active in soundtrigger
05-01 15:17:30.594  1100  5370 D audio_hw_output_stream: update stream 3 active 1 gain 0.050119
05-01 15:17:30.598  1100 13221 D audio_hw: prepare deep-buffer-playback
05-01 15:17:30.598  1100 13221 D audio_hw_soundtrigger: st_uc_monitor:uc:deep-buffer-playback active
05-01 15:17:30.598  1100 13221 D audio_hw: deep-buffer-playback:
05-01 15:17:30.598  1100 13221 D audio_hw: rx:
05-01 15:17:30.598  1100 13221 D audio_hw:   #0: OUT_SPEAKER_BE_CFG 0
05-01 15:17:30.598  1100 13221 W audio_hw_35l41: cs35l41_amp_common_event: ret: 0, event: 1, state: 2, device: 4
05-01 15:17:30.598  1100 13221 D audio_route: Apply path: speaker
05-01 15:17:30.604  1100 13221 D audio_hw_aoc_route: speaker 1
05-01 15:17:30.604  1100 13221 D audio_route: Apply path: hostless-ulC spk-vi
05-01 15:17:30.605  1100 13221 W audio_hw_35l41: cs35l41_amp_common_event: ret: 0, event: 2, state: 3, device: 4
05-01 15:17:30.605  1100 13221 D sound_trigger_platform: enable_hotword_aec: enable 1
05-01 15:17:30.606  1359  1359 D AOC     : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x00ce, tag: 0xf4 [cntr = 806]
05-01 15:17:30.606  1359  1359 D AOC     : F1:MSG: audio_capture_pdm.cc, 695: Starting PDM in Regular mode
05-01 15:17:30.656  1100 13221 D audio_hw_soundtrigger: st_comm_aud_event_monitor:codec dev:4 active
05-01 15:17:30.656  1100 13221 I audio_hw_aoc: Mode Ambient is already selected
05-01 15:17:30.656  1100 13221 D audio_route: Apply path: deep-buffer-playbackP
05-01 15:17:30.656  1359  1359 D AOC     : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x00ce, tag: 0xf4, rc: 0
05-01 15:17:30.657  1359  1359 D AOC     : F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x010f, tag: 0xff [cntr = 4458]
05-01 15:17:30.657  1100 13221 D audio_hw_aoc_route: deep-buffer-playbackP 1
05-01 15:17:30.657  1100 13221 E audio_route: unable to find path 'speaker-post'
05-01 15:17:30.657  1100 13221 D audio_hw_aoc_route: speaker-post 1
05-01 15:17:30.657  1359  1359 D AOC     : F1:Source 5 mastered by sink 0
05-01 15:17:30.657  1359  1359 D AOC     : F1:AT Map:20 (LL:0), 1 EPs active, Power: Yes, Config: No
05-01 15:17:30.658  1359  1359 D AOC     : F1:Mixer 0 configuration changed: (20)
05-01 15:17:30.658  1359  1359 D AOC     : F1:Sink 0 Configuration changed: ULL (enabled)
05-01 15:17:30.658  1359  1359 D AOC     : F1:AHWSinkSPKR started: 48 samples (32-bit,2 ch,48 kHz) block 384
05-01 15:17:30.658  1359  1359 D AOC     : F1:[AHWSinkSPKR] DL resync B: 1316, A:1316. wo: 1700, target offset: 384 (align 68) -> ro:384
05-01 15:17:30.658  1359  1359 D AOC     : F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x010f, tag: 0xff, rc: 0
05-01 15:17:30.658  1359  1359 D AOC     : H0:Mixer AMixSPKR: 480 samples (0020/0020) (ON SC)
05-01 15:17:30.658  1359  1359 D AOC     : H0:   5: 0x403e1ec0
05-01 15:17:30.658  1359  1359 D AOC     : H0:AMixSPKR PostProcessing Config: WAVES
05-01 15:17:30.658  1359  1359 D AOC     : H0:Mixer configuration updated
05-01 15:17:30.658  1359  1359 D AOC     : H0:Mixer state changed
05-01 15:17:30.659  1100 13221 D audio_hw_waves: waves_usecase_event: deep-buffer-playback start
05-01 15:17:30.659  1100 13221 D audio_hw_waves: waves_usecase_event: trigger to update tuning when first track is running.
05-01 15:17:30.659  1100 13221 D audio_hw_waves: update_sink_info_from_usecases: sink 1 VOL/Stream update 0.000000(4294967295) -> 0.050119(3)
05-01 15:17:30.659  1359  1359 D AOC     : F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00ce, tag: 0x00 [cntr = 4459]
05-01 15:17:30.659  1359  1359 D AOC     : F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x0120, tag: 0xc0, rc: 0
05-01 15:17:30.661  1100 13221 D audio_hw_waves: send_waves_tuning: instance_id = 1, tuning_id = 2
05-01 15:17:30.661  1100 13221 D audio_hw_aoc: aoc_send_rtc_mixer_tuning: block_id = 16, comp_id = 2
05-01 15:17:30.661  1359  1359 D AOC     : F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00d5, tag: 0x8a [cntr = 4460]
05-01 15:17:30.662  1359  1359 D AOC     : F1:Speaker Started (DualDMA on 2 and 3)
05-01 15:17:30.663  1359  1359 D AOC     : F1:[AHWSinkSPKR] mix overrun by 3840
05-01 15:17:30.664  1359  1359 D AOC     : H0:MSG: controller.cc, 811: AudOutCtrlH0: ipc: C-AO-H0, cmd ID: 0x00d5, tag: 0x5f, rc: 0
05-01 15:17:30.664  1359  1359 D AOC     : F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_tu, cmd ID: 0x00d5, tag: 0x5f, rc: 0
05-01 15:17:30.667  1359  1359 D AOC     : F1:MSG: controller.cc, 273: AudioOutCtrl: cmd ID: 0x00c9, tag: 0x01 [cntr = 4461]
05-01 15:17:30.667  1359  1359 D AOC     : F1:MSG: controller.cc, 811: AudioOutCtrl: ipc: audio_output_co, cmd ID: 0x00c9, tag: 0x01, rc: 0
05-01 15:17:30.672  1359  1359 D AOC     : F1:MSG: asp_src_iir.cc, 163: IIR SRC mode 6 48000Hz -> 16000Hz
05-01 15:17:30.675  9166 12567 D AudioTrack: getTimestamp_l(717): device stall time corrected using current time 73771797750741
05-01 15:17:30.746  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(47)
05-01 15:17:30.825  9166  9166 D EventLogger: loading [eventTime=1.23, mediaPos=6957.92, window=0, period=0, true]
05-01 15:17:30.834  3861  5917 I AiAiEcho: SmartspaceNotificationPredictor no parser can handle this notification or notification is invalid
05-01 15:17:30.853  2146  2146 D MediaResumeListener: Checking for service component for org.jellyfin.mobile
05-01 15:17:30.854  2146  2196 D MediaResumeListener: Testing if we can connect to ComponentInfo{org.jellyfin.mobile/org.jellyfin.mobile.player.audio.MediaService}
05-01 15:17:30.855  9166  9166 D EventLogger: loading [eventTime=1.25, mediaPos=6957.95, window=0, period=0, false]
05-01 15:17:30.858  1579  2650 I MR2ServiceImpl: startScan | manager: 1
05-01 15:17:30.860  9166  9166 W MediaSessionCompat: Couldn't find a unique registered media button receiver in the given context.
05-01 15:17:30.860 19629 19629 W DiscoveryManager: BroadcastReceiver is already registered
05-01 15:17:30.860 19629 12404 I DiscoveryManager: Filter criteria(F007D354) scannerFlags(2)
05-01 15:17:30.861 19629 12404 W DatabaseProcessor: processLocalDevices: failed to get the network info with non-null networkId.
05-01 15:17:30.861 19629 19629 I CastMediaRouteProvider: Published 0 routes
05-01 15:17:30.862  1579  2650 I MediaSessionStack: addSession to bottom of stack | record: org.jellyfin.mobile/MediaService (userId=0)
05-01 15:17:30.866  9166  9166 I ExoPlayerImpl: Init 81e156b [ExoPlayerLib/2.19.1] [bluejay, Pixel 6a, Google, 34]
05-01 15:17:30.866  2146  2196 W LocalMediaManager: BluetoothAdapter is null or muting expected device not exist
05-01 15:17:30.869  2146  2196 D MediaDeviceManager: Can not get LocalBluetoothLeBroadcast
05-01 15:17:30.870  2146  2196 D MediaDeviceManager: device is com.android.settingslib.media.PhoneMediaDevice@bf9d171, controller android.media.session.MediaController@594cb56, routingSession null or Phone
05-01 15:17:30.870  2146  2196 D MediaDeviceManager: new device name This phone
05-01 15:17:30.873  2146  2196 D MediaDeviceManager: Can not get LocalBluetoothLeBroadcast
05-01 15:17:30.874  1579  2650 I MR2ServiceImpl: setDiscoveryRequestWithRouter2 | router: org.jellyfin.mobile(id: 11), discovery request: RouteDiscoveryRequest{ preferredFeatures={com.google.android.gms.cast.CATEGORY_CAST/F007D354///ALLOW_IPV6, android.media.route.feature.REMOTE_PLAYBACK, android.media.route.feature.LIVE_AUDIO}, activeScan=false }
05-01 15:17:30.874  9166  9166 W MR2     : registerTransferCallback: Ignoring the same callback
05-01 15:17:30.874  9166  9166 W MR2     : registerControllerCallback: Ignoring the same callback
05-01 15:17:30.875 19629 19629 W DiscoveryManager: BroadcastReceiver is already registered
05-01 15:17:30.875 19629 11689 I DiscoveryManager: Filter criteria(F007D354,CC1AD845) scannerFlags(2)
05-01 15:17:30.876 19629 11689 W DatabaseProcessor: processLocalDevices: failed to get the network info with non-null networkId.
05-01 15:17:30.876  2146  2196 D MediaDeviceManager: device is com.android.settingslib.media.PhoneMediaDevice@bf9d171, controller android.media.session.MediaController@594cb56, routingSession null or Phone
05-01 15:17:30.883  2146  2196 D MediaDeviceManager: new device name This phone
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown center_vertical_guideline
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown action_button_guideline
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown icon
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown media_seamless
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown media_action_barrier_start
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown media_action_barrier_end
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown media_action_barrier_top
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown remove_text
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown settings
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown dismiss
05-01 15:17:30.884  2146  2146 W ConstraintSet: id unknown cancel
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown center_vertical_guideline
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown action_button_guideline
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown icon
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown media_seamless
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown media_action_barrier_start
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown media_action_barrier_end
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown media_action_barrier_top
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown remove_text
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown settings
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown dismiss
05-01 15:17:30.887  2146  2146 W ConstraintSet: id unknown cancel
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown center_vertical_guideline
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown action_button_guideline
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown icon
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown media_seamless
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown media_action_barrier_start
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown media_action_barrier_end
05-01 15:17:30.888  2146  2146 W ConstraintSet: id unknown media_action_barrier_top
05-01 15:17:30.889  2146  2146 W ConstraintSet: id unknown remove_text
05-01 15:17:30.889  2146  2146 W ConstraintSet: id unknown settings
05-01 15:17:30.889  2146  2146 W ConstraintSet: id unknown dismiss
05-01 15:17:30.889  2146  2146 W ConstraintSet: id unknown cancel
05-01 15:17:30.893  2146 12581 W LocalMediaManager: BluetoothAdapter is null or muting expected device not exist
05-01 15:17:30.917  2146  2196 D MediaDeviceManager: Can not get LocalBluetoothLeBroadcast
05-01 15:17:30.918  2146  2196 D MediaDeviceManager: device is com.android.settingslib.media.PhoneMediaDevice@b8ecb17, controller android.media.session.MediaController@594cb56, routingSession null or Phone
05-01 15:17:30.918  2146  2196 D MediaDeviceManager: new device name This phone
05-01 15:17:30.919  2146  2196 D ResumeMediaBrowser: Service connected for ComponentInfo{org.jellyfin.mobile/org.jellyfin.mobile.player.audio.MediaService}
05-01 15:17:30.920  2146  2196 D MediaResumeListener: Connected to ComponentInfo{org.jellyfin.mobile/org.jellyfin.mobile.player.audio.MediaService}
05-01 15:17:30.962 19629 19629 I CastMediaRouteProvider: Published 0 routes
05-01 15:17:30.978  2146  2196 D ResumeMediaBrowser: No children found for ComponentInfo{org.jellyfin.mobile/org.jellyfin.mobile.player.audio.MediaService}
05-01 15:17:30.978  2146  2196 E MediaResumeListener: Cannot resume with ComponentInfo{org.jellyfin.mobile/org.jellyfin.mobile.player.audio.MediaService}
05-01 15:17:30.982  1579  2650 I MediaSessionStack: removeSession | record: org.jellyfin.mobile/MediaService (userId=0)
05-01 15:17:30.982  9166  9166 I ExoPlayerImpl: Release 81e156b [ExoPlayerLib/2.19.1] [bluejay, Pixel 6a, Google, 34] [goog.exo.core, goog.exo.exoplayer, goog.exo.decoder, goog.exo.ui, goog.exo.cronet, goog.exo.datasource, goog.exo.extractor, goog.exo.ffmpeg, goog.exo.mediasession, goog.exo.cast]
05-01 15:17:30.986  1579  2272 I MR2ServiceImpl: setDiscoveryRequestWithRouter2 | router: org.jellyfin.mobile(id: 11), discovery request: RouteDiscoveryRequest{ preferredFeatures={com.google.android.gms.cast.CATEGORY_CAST/F007D354///ALLOW_IPV6, android.media.route.feature.LIVE_AUDIO}, activeScan=false }
05-01 15:17:30.986  9166  9166 W MR2     : registerTransferCallback: Ignoring the same callback
05-01 15:17:30.986  9166  9166 W MR2     : registerControllerCallback: Ignoring the same callback
05-01 15:17:30.988 19629 19629 W DiscoveryManager: BroadcastReceiver is already registered
05-01 15:17:30.988 19629 12404 I DiscoveryManager: Filter criteria(F007D354) scannerFlags(2)
05-01 15:17:30.989 19629 12404 W DatabaseProcessor: processLocalDevices: failed to get the network info with non-null networkId.
05-01 15:17:30.990  2146 12581 W LocalMediaManager: BluetoothAdapter is null or muting expected device not exist
05-01 15:17:30.990  2146  2196 D MediaDeviceManager: Can not get LocalBluetoothLeBroadcast
05-01 15:17:30.991  2146  2196 D MediaDeviceManager: device is com.android.settingslib.media.PhoneMediaDevice@e5b98a5, controller android.media.session.MediaController@594cb56, routingSession null or Phone
05-01 15:17:30.991  2146  2196 D MediaDeviceManager: new device name This phone
05-01 15:17:31.055  2146  2146 W ConstraintSet: id unknown center_vertical_guideline
05-01 15:17:31.055  2146  2146 W ConstraintSet: id unknown action_button_guideline
05-01 15:17:31.055  2146  2146 W ConstraintSet: id unknown icon
05-01 15:17:31.055  2146  2146 W ConstraintSet: id unknown media_seamless
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown media_action_barrier_start
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown media_action_barrier_end
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown media_action_barrier_top
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown remove_text
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown settings
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown dismiss
05-01 15:17:31.056  2146  2146 W ConstraintSet: id unknown cancel
05-01 15:17:31.065 19629 19629 I CastMediaRouteProvider: Published 0 routes
05-01 15:17:31.172  9166  9166 D EventLogger: loading [eventTime=1.57, mediaPos=6958.25, window=0, period=0, true]
05-01 15:17:31.273  9166  9166 D EventLogger: loading [eventTime=1.67, mediaPos=6958.34, window=0, period=0, false]
05-01 15:17:31.535  9166  9166 D EventLogger: loading [eventTime=1.93, mediaPos=6958.60, window=0, period=0, true]
05-01 15:17:31.625  9166  9166 D EventLogger: loading [eventTime=2.02, mediaPos=6958.68, window=0, period=0, false]
05-01 15:17:31.634  4268  4268 I wavelet:service: Explicit concurrent mark compact GC freed 412(128KB) AllocSpace objects, 0(0B) LOS objects, 87% free, 3578KB/27MB, paused 862us,2.756ms total 41.047ms
05-01 15:17:31.674  2146  2146 W ConstraintSet: id unknown center_vertical_guideline
05-01 15:17:31.674  2146  2146 W ConstraintSet: id unknown action_button_guideline
05-01 15:17:31.674  2146  2146 W ConstraintSet: id unknown icon
05-01 15:17:31.674  2146  2146 W ConstraintSet: id unknown media_seamless
05-01 15:17:31.675  2146  2146 W ConstraintSet: id unknown media_action_barrier_start
05-01 15:17:31.675  2146  2146 W ConstraintSet: id unknown media_action_barrier_end
05-01 15:17:31.675  2146  2146 W ConstraintSet: id unknown media_action_barrier_top
05-01 15:17:31.675  2146  2146 W ConstraintSet: id unknown remove_text
05-01 15:17:31.675  2146  2146 W ConstraintSet: id unknown settings
05-01 15:17:31.676  2146  2146 W ConstraintSet: id unknown dismiss
05-01 15:17:31.676  2146  2146 W ConstraintSet: id unknown cancel
05-01 15:17:31.695  1100  1678 D audio_hw_35l41: cs35l41_rtlogger_data_analysis: Left/Top impedance: 599513
05-01 15:17:31.695  1100  1678 D audio_hw_35l41: cs35l41_rtlogger_data_analysis: Right/Bottom impedance: 707404
05-01 15:17:31.696  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Left/Top Max temp: 0xad600 ==> 43.343750 C
05-01 15:17:31.696  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Left/Top Max exc: 0x39999 ==> 0.449999 mm
05-01 15:17:31.696  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Left/Top dsp heartbeat = 0x14d57
05-01 15:17:31.696  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Right/Bottom Max temp: 0x13b1b4 ==> 78.776611 C
05-01 15:17:31.696  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Right/Bottom Max exc: 0x33333 ==> 0.400000 mm
05-01 15:17:31.697  1100  1678 D audio_hw_35l41: cs35l41_bdlogger_data_analysis: Right/Bottom dsp heartbeat = 0x17e64
05-01 15:17:31.833  9166  9166 D EventLogger: loading [eventTime=2.23, mediaPos=6958.88, window=0, period=0, true]
05-01 15:17:31.924  9166  9166 D EventLogger: loading [eventTime=2.32, mediaPos=6958.97, window=0, period=0, false]
05-01 15:17:32.041  9166  9166 D EventLogger: loading [eventTime=2.44, mediaPos=6959.09, window=0, period=0, true]
05-01 15:17:32.139  9166  9166 D EventLogger: loading [eventTime=2.54, mediaPos=6959.18, window=0, period=0, false]
05-01 15:17:32.417  9166  9166 D EventLogger: loading [eventTime=2.82, mediaPos=6959.47, window=0, period=0, true]
05-01 15:17:32.506  9166  9166 D EventLogger: loading [eventTime=2.91, mediaPos=6959.56, window=0, period=0, false]
05-01 15:17:32.756  9166  9166 D EventLogger: loading [eventTime=3.15, mediaPos=6959.81, window=0, period=0, true]
05-01 15:17:32.824  9166  9166 D EventLogger: loading [eventTime=3.22, mediaPos=6959.88, window=0, period=0, false]
05-01 15:17:32.985  5875  5919 I appj    : reclaimMemory: Clearing CloudSyncCache
05-01 15:17:32.987  5875  5919 W Bugle   : TextClassifierLibManagerImpl: Reclaiming memory at level: 40
05-01 15:17:32.993  1370  1626 D CHRE    : @ 156113.096: Parsed nanoapp message from host: app ID 0x476f6f676c00100b endpoint 0x14 msgType 112 payload size 10
05-01 15:17:32.993  1370  1626 I CHRE    : @ 156113.096: [NanoMinion] received MSG Type 0x70, Version 1, 6 bytes
05-01 15:17:32.993  1370  1626 I CHRE    : @ 156113.096: [AR] request.flush
05-01 15:17:32.993  1370  1626 I CHRE    : @ 156113.096: [AR] send to host, reason=3, sampleSize=5, transitionSize=0, lastAccelTime = 156010624, lastAudioTime = 0
05-01 15:17:32.993  1370  1626 I CHRE    : @ 156113.097: [NanoMinion] sent MSG Type 0x72, Version 0, 98 bytes, MessageId 248
05-01 15:17:32.994  1107  1138 D ContextHubHal: Got message from nanoapp: ID 0x476f6f676c00100b
05-01 15:17:33.014  1370  1626 D CHRE    : @ 156113.117: Nanoapp list request from client ID 512
05-01 15:17:33.014  1107  1138 D ContextHubHal: Got nanoapp list response with 17 apps
05-01 15:17:33.018  1370  1626 D CHRE    : @ 156113.123: Nanoapp list request from client ID 512
05-01 15:17:33.019  1107  1138 D ContextHubHal: Got nanoapp list response with 17 apps
05-01 15:17:33.021  1579  1598 W AppOps  : Noting op not finished: uid 10166 pkg com.google.android.gms code 113 startTime of in progress event=1714597950618
05-01 15:17:33.028  1579  2760 W AppOps  : Noting op not finished: uid 10166 pkg com.google.android.gms code 113 startTime of in progress event=1714597950618
05-01 15:17:33.040  1579  1598 W AppOps  : Noting op not finished: uid 10201 pkg com.google.android.apps.maps code 79 startTime of in progress event=1714598118972
05-01 15:17:33.043  9166  9166 D EventLogger: loading [eventTime=3.44, mediaPos=6960.09, window=0, period=0, true]
05-01 15:17:33.050  1579  1770 D ActivityManager: sync unfroze 28168 com.google.android.apps.maps for 3
05-01 15:17:33.052  1579  2760 W AppOps  : Noting op not finished: uid 10166 pkg com.google.android.gms code 113 startTime of in progress event=1714597950618
05-01 15:17:33.060  1579  2760 D ActivityManager: sync unfroze 2384 com.google.android.projection.gearhead:car for 6
05-01 15:17:33.061 19629 19818 I ActivityScheduler: nextTriggerTime: 156250567, in 120000ms, detectorType: 0,  alarmWindowMillis: 15000
05-01 15:17:33.063  5875  5931 I appj    : reclaimMemory: Clearing CloudSyncCache
05-01 15:17:33.064  5875  5931 W Bugle   : TextClassifierLibManagerImpl: Reclaiming memory at level: 40
05-01 15:17:33.066  1370  1626 D CHRE    : @ 156113.171: Nanoapp list request from client ID 512
05-01 15:17:33.066  1107  1138 D ContextHubHal: Got nanoapp list response with 17 apps
05-01 15:17:33.071  1370  1626 D CHRE    : @ 156113.175: Nanoapp list request from client ID 512
05-01 15:17:33.071  1107  1138 D ContextHubHal: Got nanoapp list response with 17 apps
05-01 15:17:33.097  9166  9166 D EventLogger: loading [eventTime=3.50, mediaPos=6960.14, window=0, period=0, false]
05-01 15:17:33.420  9166  9166 D EventLogger: loading [eventTime=3.82, mediaPos=6960.47, window=0, period=0, true]
05-01 15:17:33.513  1171  1171 I WifiHAL : Creating message to get link statistics; iface = 47
05-01 15:17:33.534  1579  2272 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.538  1579  2272 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.545  1579  4844 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.546  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(8)
05-01 15:17:33.547  1579  1598 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.548  9166  9166 D EventLogger: loading [eventTime=3.95, mediaPos=6960.59, window=0, period=0, false]
05-01 15:17:33.557  1171  1171 I WifiHAL : In GetLinkStatsCommand::handleResponse
05-01 15:17:33.560  1579  2272 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.560  1579  2760 E AppOps  : Operation not found: uid=1041 pkg=audioserver(null) op=WAKE_LOCK
05-01 15:17:33.587  2778  2896 D IwlanNetworkService: onCapabilitiesChanged: 121
05-01 15:17:33.588  2778  2866 D IwlanDataService: onCapabilitiesChanged: 121 [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED LinkUpBandwidth>=12136Kbps LinkDnBandwidth>=155812Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.4.59, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 6, RSSI: -51, Link speed: 907Mbps, Tx Link speed: 907Mbps, Max Supported Tx Link speed: 1200Mbps, Rx Link speed: 960Mbps, Max Supported Rx Link speed: 1200Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: 1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>> SignalStrength: -51 AdminUids: [1000] SSID: "Friedman upstairs 2G" UnderlyingNetworks: Null]
05-01 15:17:33.588  2778  2866 D IwlanDataService: Network 121 connected using transport WIFI
05-01 15:17:33.589  2497  3411 D SHANNON_RCS: 1904 [0][PROX][TELE] onCapabilitiesChanged, Network: 121, NetworkCapabilities: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED LinkUpBandwidth>=12136Kbps LinkDnBandwidth>=155812Kbps TransportInfo: <SSID: <unknown ssid>, BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.4.59, Security type: 4, Supplicant state: COMPLETED, Wi-Fi standard: 6, RSSI: -51, Link speed: 907Mbps, Tx Link speed: 907Mbps, Max Supported Tx Link speed: 1200Mbps, Rx Link speed: 960Mbps, Max Supported Rx Link speed: 1200Mbps, Frequency: 5180MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: <none>, Provider friendly name: <none>, Requesting package name: <none><none>MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: <none>, AP MLO Link Id: <none>, AP MLO Affiliated links: <none>> SignalStrength: -51 UnderlyingNetworks: Null] (DefaultNetworkCallback%onCapabilitiesChanged:76)
05-01 15:17:33.594 28591 28662 I BugleRcsEngine: handleMessage processing message:[NOTIFY_UPTIME_IGNORE_STATE_CHANGED] with [non-null]:RcsEngineImpl reference [CONTEXT log_prefix="RcsEngineImpl[DUAL_REG]:[9b80aa03-ec26]>Handler" thread_id=81 ]
05-01 15:17:33.595 28591 28662 W BugleRcsEngine: Cannot use CS.apk as it's battery optimized [CONTEXT thread_id=81 ]
05-01 15:17:33.596 24852 24909 D DeviceStateHelper: Wifi freq: 5180
05-01 15:17:33.596 28591 28654 I BugleRcsEngine: Connected state: [1], networkType: [WIFI] [CONTEXT thread_id=78 ]
05-01 15:17:33.596 24852 24923 I WifiSignalInfo: onCapabilitiesChanged: rssi:-51
05-01 15:17:33.604 28591 28662 I BugleRcsEngine: Rcs is enabled from user settings: true [CONTEXT log_prefix="ProvisioningEngineDataRetriever" thread_id=81 ]
05-01 15:17:33.609  1579  2080 D ConnectivityService: NetReassign [no changes] [c 1] [a 1] [i 3]
05-01 15:17:33.747  9166  9166 D EventLogger: loading [eventTime=4.15, mediaPos=6960.80, window=0, period=0, true]
05-01 15:17:33.772  9166  9166 D EventLogger: loading [eventTime=4.17, mediaPos=6960.82, window=0, period=0, false]
05-01 15:17:33.867  1579  1594 I system_server: Background concurrent mark compact GC freed 3462803(100MB) AllocSpace objects, 15(340KB) LOS objects, 60% free, 63MB/159MB, paused 1.109ms,4.637ms total 322.060ms
05-01 15:17:33.892  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/base.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesA.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesA.config.en.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesA.config.xxhdpi.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesC.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesC.config.en.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_DynamiteModulesC.config.xxhdpi.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_GoogleCertificates.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_MapsDynamite.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_MapsDynamite.config.en.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_MapsDynamite.config.xxhdpi.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_MeasurementDynamite.apk' with 1 weak references
05-01 15:17:33.893  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_MeasurementDynamite.config.en.apk' with 1 weak references
05-01 15:17:33.894  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_config.en.apk' with 1 weak references
05-01 15:17:33.894  1579  1596 W system_server: ApkAssets: Deleting an ApkAssets object '<empty> and /data/app/~~wcnHroUncI0-8CWS2-29KA==/com.google.android.gms-R_rLsdYx5c4iEm6GaqcC-g==/split_config.xxhdpi.apk' with 1 weak references
05-01 15:17:33.895  1579  1596 W System  : A resource failed to call CursorWindow.close.
05-01 15:17:33.990 19629 11689 W AdaptiveDiscoveryWorker: Exiting Adaptive Discovery. Current NetworkInfo is NULL.
05-01 15:17:34.045  9166  9166 D EventLogger: loading [eventTime=4.44, mediaPos=6961.09, window=0, period=0, true]
05-01 15:17:34.131  9166  9166 D EventLogger: loading [eventTime=4.53, mediaPos=6961.18, window=0, period=0, false]
05-01 15:17:34.340  9166  9166 D EventLogger: loading [eventTime=4.74, mediaPos=6961.39, window=0, period=0, true]
05-01 15:17:34.441  9166  9166 D EventLogger: loading [eventTime=4.84, mediaPos=6961.48, window=0, period=0, false]
05-01 15:17:34.715  9166  9166 D EventLogger: loading [eventTime=5.11, mediaPos=6961.76, window=0, period=0, true]
05-01 15:17:34.808  9166  9166 D EventLogger: loading [eventTime=5.21, mediaPos=6961.86, window=0, period=0, false]
05-01 15:17:34.845  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(3)
05-01 15:17:35.043  9166  9166 D EventLogger: loading [eventTime=5.44, mediaPos=6962.09, window=0, period=0, true]
05-01 15:17:35.122  9166  9166 D EventLogger: loading [eventTime=5.52, mediaPos=6962.17, window=0, period=0, false]
05-01 15:17:35.335  9166  9166 D EventLogger: loading [eventTime=5.73, mediaPos=6962.38, window=0, period=0, true]
05-01 15:17:35.425  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(4)
05-01 15:17:35.428  9166  9166 D EventLogger: loading [eventTime=5.83, mediaPos=6962.48, window=0, period=0, false]
05-01 15:17:35.718  9166  9166 D EventLogger: loading [eventTime=6.12, mediaPos=6962.77, window=0, period=0, true]
05-01 15:17:35.803  9166  9166 D EventLogger: loading [eventTime=6.20, mediaPos=6962.85, window=0, period=0, false]
05-01 15:17:35.825  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(4)
05-01 15:17:36.044  9166  9166 D EventLogger: loading [eventTime=6.44, mediaPos=6963.09, window=0, period=0, true]
05-01 15:17:36.149  9166  9166 D EventLogger: loading [eventTime=6.55, mediaPos=6963.19, window=0, period=0, false]
05-01 15:17:36.252  1370  1626 I CHRE    : @ 156116.350: [ip] Peak detected: magnitude = 0.275576 hpa, duration = 79.000002 ms
05-01 15:17:36.253  1107  1138 D ContextHubHal: Got message from nanoapp: ID 0x476f6f676c001022
05-01 15:17:36.253  1137  1883 I suez-nanoapp-clients: Barometric peak detected: magnitude = 0.275577 hPa (bucket: 6), duration = 79.000000 ms (bucket: 7).
05-01 15:17:36.257  1137  1883 I suez-nanoapp-clients: Vendor atom [id = 100047] reported.
05-01 15:17:36.343  9166  9166 D EventLogger: loading [eventTime=6.74, mediaPos=6963.39, window=0, period=0, true]
05-01 15:17:36.448  9166  9166 D EventLogger: loading [eventTime=6.85, mediaPos=6963.50, window=0, period=0, false]
05-01 15:17:36.506  1174  1772 D AudioFlinger: mixer(0xb4000071867e7910) throttle end: throttle time(7)


======================Server logs===============================================
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : play_method          = "DirectPlay"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.ClientName         = "Jellyfin Android"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.DeviceName         = "Pixel 6a"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemName             = "The Hobbit: An Unexpected Journey"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemId               = "de5318fc5ae495a0f6a4742e6e87adf9"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemType             = "Movie"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : All matches, playback registered
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback tracker found, adding playback info : "8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9"
[2024-05-01 14:35:28.877 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2024-05-01 14:35:28.887 -06:00] [INF] [80] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Exited
[2024-05-01 14:35:37.331 -06:00] [WRN] [122] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "192.168.4.59" error receiving data: "The remote party closed the WebSocket connection without completing the close handshake."
[2024-05-01 14:35:37.338 -06:00] [INF] [122] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "192.168.4.59" closed
[2024-05-01 14:44:19.197 -06:00] [INF] [8] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "192.168.4.59" request
[2024-05-01 14:44:27.414 -06:00] [INF] [76] Jellyfin.Api.Helpers.MediaInfoHelper: User policy for "jason". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True
[2024-05-01 14:44:27.414 -06:00] [INF] [76] Jellyfin.Api.Helpers.MediaInfoHelper: StreamBuilder.BuildVideoItem( Profile="Jellyfin Android", Path="/media/library/movies/The Hobbit - An Unexpected Journey (2012)/The Hobbit An Unexpected Journey (2012) [imdbid-tt0903624] - Extended Cut [Remux-2160p][DV HDR10][TrueHD Atmos 7.1][HEVC]-FraMeSToR.mkv", AudioStreamIndex=1, SubtitleStreamIndex=-1 ) => ( PlayMethod=DirectPlay, TranscodeReason=0 ) "media:/videos/de5318fc-5ae4-95a0-f6a4-742e6e87adf9/stream.mkv?MediaSourceId=de5318fc5ae495a0f6a4742e6e87adf9&Static=true&VideoCodec=hevc&AudioCodec=truehd&AudioStreamIndex=1&api_key=<token>&SubtitleMethod=Encode&Tag=322245376660ee690923f9dd3aaa3d1b"
[2024-05-01 14:44:27.495 -06:00] [INF] [76] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9"
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Existing tracker found! : 8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving existing playback tracking activity in DB
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Removing existing tracker : 8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Adding playback tracker : 8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Start Event : 05/01/2024 14:44:27
[2024-05-01 14:44:27.554 -06:00] [INF] [119] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Creating StartPlaybackTimer Task
[2024-05-01 14:44:27.554 -06:00] [INF] [95] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Entered
[2024-05-01 14:44:28.318 -06:00] [INF] [141] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "8f838d93050aff4825218dfd21a94b419abbc4460ea1bcbe-25218dfd21a94b419abbc4460ea1bcbe-de5318fc5ae495a0f6a4742e6e87adf9"
[2024-05-01 14:44:40.414 -06:00] [INF] [110] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "192.168.0.5" closed
[2024-05-01 14:44:40.832 -06:00] [INF] [8] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "192.168.0.5" request

Application version

2.6.1

Where did you install the app from?

None

Device information

Pixel 6a

Android version

Android 14

Jellyfin server version

10.8.13

Which video player implementations does this bug apply to?

  • Web player (default)
  • Integrated player (ExoPlayer)
  • External player (VLC, mpv, MX Player)
@jasonafriedman jasonafriedman added the bug Something isn't working label May 1, 2024
@levogevo
Copy link

levogevo commented May 8, 2024

What happens if you try to play the video using an external player and choosing the complete stock OS-bundled video player?

@jasonafriedman
Copy link
Author

The DV file does not play in stock video player or cx file explorer. It does play in VLC, I think that has some software/hardware decoding available. I have upgraded to JF server 10.9 and the issue persists. Looking at my network bandwidth it looks like jellyfin is sending the full file to the client and the client is receiving it and playing it just as a black screen. Once I turn down the bitrate to 1080p 40mbps, the client triggers a transcode on the server. Tone mapping works properly on 10.9 with my AMD gpu so the client just needs a manual override to lower the resolution and force a transcode because otherwise it will play a black video.

@satmandu
Copy link

FYI there is now a PR for adding DV support to androidx/media: androidx/media#1235

@tykateetee
Copy link

tykateetee commented Aug 5, 2024

First time commenting here - can agree to this being an issue. Server is running in an Unraid docker container on version 10.9.8 and the client is the Jellyfin android app on two different devices - Galaxy S10 and Galaxy Tab S7 both on version 2.6.2. Both clients show a black screen when direct playing 4k HEVC DV HDR10 (Profile 7.6) content using the Integrated player. Audio works as intended.

This is the media info for a file that does playback correctly:
The.Beekeeper.2024.REMUX-2160P.HEVC.TRUEHD.ATMOS.7.1.DV.HDR10
Containermkv
Path/media/movies/The Beekeeper (2024)/The.Beekeeper.2024.REMUX-2160P.HEVC.TRUEHD.ATMOS.7.1.DV.HDR10.mkv
Size56273 MB
Video
Title4K HEVC HDR
CodecHEVC
AVCNo
ProfileMain 10
Level153
Resolution3840x2160
Aspect ratio16:9
AnamorphicNo
InterlacedNo
Framerate23.976025
Bitrate74500 kbps
Bit depth10 bit
Video rangeHDR
Video range typeDOVIWithHDR10
DV titleDV Profile 8.1 (HDR10)
DV version major1
DV version minor0
DV profile8
DV level6
DV rpu preset flag1
DV el preset flag0
DV bl preset flag1
DV bl signal compatibility id1
Color spacebt2020nc
Color transfersmpte2084
Color primariesbt2020
Pixel formatyuv420p10le
Ref frames1

And here is one that only shows a black screen:
The.Lord.of.the.Rings.The.Return.of.the.King.2003.REMUX-2160P.PROPER.HEVC.TRUEHD.ATMOS.7.1.DV.HDR10
Containermkv
Path/media/movies/The Lord of the Rings The Return of the King (2003)/The.Lord.of.the.Rings.The.Return.of.the.King.2003.REMUX-2160P.PROPER.HEVC.TRUEHD.ATMOS.7.1.DV.HDR10.mkv
Size161280 MB
Video
Title4K HEVC HDR
CodecHEVC
AVCNo
ProfileMain 10
Level153
Resolution3840x2160
Aspect ratio16:9
AnamorphicNo
InterlacedNo
Framerate23.976025
Bitrate85639 kbps
Bit depth10 bit
Video rangeHDR
Video range typeHDR10
DV titleDV Profile 7.6
DV version major1
DV version minor0
DV profile7
DV level6
DV rpu preset flag1
DV el preset flag1
DV bl preset flag1
DV bl signal compatibility id6
Color spacebt2020nc
Color transfersmpte2084
Color primariesbt2020
Pixel formatyuv420p10le
Ref frames1

Excluding file sizes and bitrate, there were small differences in the media info:
Video range typeDOVIWithHDR10
DV titleDV Profile 8.1 (HDR10)
DV profile8
DV el preset flag0
DV bl signal compatibility id1

Versus the file that is only a black screen:
Video range typeHDR10
DV titleDV Profile 7.6
DV profile7
DV el preset flag1
DV bl signal compatibility id6

Original commenter's media info:
Video range typeHDR10
DV titleDV Profile 7.6
DV profile7
DV el preset flag1
DV bl signal compatibility id6

Noting the fact that both the file I had issues with and the one the OP had issues with were using DV profile 7.6, I went hunting for other 4k remux DV content. These were the results:
16x Files that reported Video range typeHDR10 and DV titleDV Profile 7.6 would only display a black screen with audio.
4x Files that reported Video range typeDOVIWithHDR10 and DV titleDV Profile 8.1 (HDR10) would play back as expected.

Wondering if remuxes were the only type of content plagued by this issue I switched to 4k web files, however I only could find ones with Video range typeDOVIWithHDR10 + DV titleDV Profile 8.1 (HDR10) and the 10 I tried all displayed correctly.

I also attempted to use the Jellyfin for Android TV app on the Galaxy S10 to see if it would work, but it uses the same integrated player and still produces the same result. Using Jellyfin Media Player on windows and the Jellyfin for Android TV app on a Sony TV all content including DV profile 7.6 played correctly, so this issue is not on windows and is not on all android powered devices. I am by no means knolowdgeable enough to understand why profile 7.6 does not work while 8.1 does, but I assume that the latter is falling back to HDR10 for devices that do not support DV, while the former is not and is sending an incompatable stream to the device. The logs all look normal, and do not differ from what the OP put in their post. I agree with the follow up comment from the OP that the network activity appears as the server is sending the full bandwith needed to the client device as if the file is playing normally. Forcing any sort of transcode by lowering the quality/bitrate plays the file as intended, with me having no issues with tone mapping using a GTX 1660 Super.

I don't need the DV profile at all as I am fine with it falling back to HDR10, however I agree with the OP that the app should not allow the file to direct play if it will produce a black screen, which in my testing was DV profile 7.6 content on android devices that do not appear to support DV.

Eager to hear what comes of this as I'm only realizing now how much DV profile 7.6 content I have as I normally playback on windows, so I never noticed this issue until now. Will gladly do more testing if needed.

Edit: Using the integrated video player on the Samsung devices DID work, and the same goes for using VLC. This issue for me only occurs with ExoPlayer.

@realFPS
Copy link

realFPS commented Oct 8, 2024

Use Just player as an external player. That app is also using Exoplayer but it has an option in settings which supports HDR fallback for DV profile 7 files.

@tykateetee
Copy link

While I'm sure this works as for me VLC and Samsung's integrated player both did, it doesn't address the main issue. The file shouldn't be served directly if there will be an issue playing it back natively on the app using any android device such as Pixel 6a or a Galaxy S10.

As per the OP's original issue:

If DV is not supported on the pixel 6a, then the app should not allow a broken direct play and force server transcoding

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

5 participants