Motorola Community

Unlockable PhonesMOTO X™ (DEVELOPER EDITION)
All Forum Topics
Options

1238 Posts

11-15-2013

US

440 Signins

4527 Page Views

  • Posts: 1238
  • Registered: ‎11-15-2013
  • Location: US
  • Views: 4527
  • Message 1 of 2

Excessive delay reading Vsync and SFPerfTracer

2014-03-16, 15:51 PM


I keep noticing this in my logs, and this is an issue because it results in runt frames so the displayed result isn't always as smooth as it should be.



Here's the Excessive delay reading Vsync:


 



W/qdhwcomposer(  230): Excessive delay reading vsync: took 216 ms


 


I/MediaFocusControl(  872):  AudioFocus  abandonAudioFocus() from android.media.AudioManager@43089cf0com.netflix.mediaclient.ui.player.PlayerActivity@4288fcd0


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 83 ms


 


D/MobileDataStateTracker(  872): default: setPolicyDataEnable(enabled=true)


 


I/SFPerfTracer(  230):      triggers: (rate: 0:0) (261405 sw vsyncs) (0 skipped) (27:608297 vsyncs) (29:1060306)


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 366 ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 99 ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 266 ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 249 ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 334 ms


 


I/SFPerfTracer(  230):      triggers: (rate: 7516:186417) (compose: 0:58) (post: 0:258) (render: 0:188) (0:400911 frames) (1:887569)


 


D/SFPerfTracer(  230):        layers: (3:11) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3222)* (StatusBar: 0:23425) (NavigationBar: 0:16942) (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:341)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:284)* (SurfaceView: 0:9358)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.home.HomeActivity: 1:70) 


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 167 ms


 


D/ALSADevice(  235): standby: handle 0xb85d31d0 h 0x0


 


D/alsa_ucm(  235): snd_use_case_set(): uc_mgr 0xb85b50e8 identifier _verb value Inactive


 


D/alsa_ucm(  235): Set mixer controls for HiFiMI2S Speaker enable 0


 


D/alsa_ucm(  235): snd_use_case_set(): uc_mgr 0xb85b50e8 identifier _disdev value MI2S Speaker


 


D/alsa_ucm(  235): Set mixer controls for MI2S Speaker enable 0


 


D/AudioUsbALSA(  235): exitPlaybackThread, mproxypfdPlayback: -1


 


D/AudioUsbALSA(  235): closeDevice handle 0x0


 


D/AudioUsbALSA(  235): closeDevice handle 0x0


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 400 ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 181 ms


 


I/SFPerfTracer(  230):      triggers: (rate: 0:0) (261405 sw vsyncs) (0 skipped) (57:608419 vsyncs) (59:1060440)


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 718 ms


 


I/SBar.NetworkController( 1069): onDataActivity: direction=0


 


I/SBar.NetworkController( 1069): refreshSignalCluster: mobile: mHasMobileDataFeature=true DataTypeShown=zz_moto_stat_sys_data_fully_connected_4g_lte_wide Activity=zz_moto_stat_sys_data_fully_connected_signal_none_wide in=false out=false Accessibility="4 G L T E"


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 400 ms


 


D/dalvikvm(12743): GC_CONCURRENT freed 7429K, 12% free 59131K/67068K, paused 8ms+19ms, total 96ms


 


W/qdhwcomposer(  230): Excessive delay reading vsync: took 249 ms


 


I/SFPerfTracer(  230):      triggers: (rate: 7516:186417) (compose: 0:58) (post: 0:258) (render: 0:188) (0:400911 frames) (1:887669)


 


D/SFPerfTracer(  230):        layers: (3:9) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3222)* (StatusBar: 0:23425) (NavigationBar: 0:16942) (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:341)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.home.HomeActivity: 1:170)


 


SFPerfTracer is also noting the Vsync issues too.


 


Also, when playing video from Netflix the SFPerfTracer keeps going off even though Netflix is trying to keep a target framerate of 24fps for movie/show watching.  Could this be causing the audio/video to go out of sync as the system keeps excessively logging these instances in the background or is it a performance issue with the driver itself or something else?  My log is full of these instances while watching Netflix.  And trying to watch something in Netflix has been extremely trying ever since KitKat.  My Nexus 7 LTE doesn't have the audio/video sync issue, so something is at work here.




Here's a sample of my log while watching an HD TV show in Netflix:



I/SFPerfTracer(  230):       trigger: frame rate (-58.615%) (24.831 fps) (40.272 ms) (21 drops) (19 frames)


 


I/SFPerfTracer(  230):      triggers: (rate: 7256:180124) (compose: 0:58) (post: 0:257) (render: 0:188) (19:393754 frames) (20:879004)


 


D/SFPerfTracer(  230):        layers: (2:10) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3220)* (StatusBar: 1:23173)* (NavigationBar: 0:16821)* (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:329)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:216) (SurfaceView: 20:1082) 


 


I/SFPerfTracer(  230):       trigger: frame rate (-58.137%) (25.118 fps) (39.813 ms) (11 drops) (13 frames)


 


I/SFPerfTracer(  230):      triggers: (rate: 7257:180135) (compose: 0:58) (post: 0:257) (render: 0:188) (13:393767 frames) (14:879018)


 


D/SFPerfTracer(  230):        layers: (2:10) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3220)* (StatusBar: 0:23173)* (NavigationBar: 0:16821)* (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:329)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:216) (SurfaceView: 14:1096) 


 


I/SFPerfTracer(  230):       trigger: frame rate (-58.147%) (25.112 fps) (39.822 ms) (17 drops) (18 frames)


 


I/SFPerfTracer(  230):      triggers: (rate: 7258:180157) (compose: 0:58) (post: 0:257) (render: 0:188) (18:393792 frames) (19:879046)


 


D/SFPerfTracer(  230):        layers: (2:10) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3220)* (StatusBar: 0:23173)* (NavigationBar: 0:16821)* (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:329)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:216) (SurfaceView: 19:1124) 


 


I/SBar.NetworkController( 1069): onSignalStrengthsChanged SignalStrength: 99 0 -95 -155 -120 -1 -1 17 -114 -18 -10 2147483647 2147483647 cdma 0 -108 -1 false 0 0 1 0 2 99 99 99 5 level=2


 


I/SBar.NetworkController( 1069): refreshSignalCluster: mobile: Signal=zz_moto_stat_sys_signal_5bar_2_fully_wide Roaming=(none) mSimIconId=(none) Accessibility="Phone two bars.","Not roaming.",""


 


I/SFPerfTracer(  230):       trigger: frame rate (-54.952%) (27.029 fps) (36.997 ms) (12 drops) (14 frames)


 


I/SFPerfTracer(  230):      triggers: (rate: 7259:180189) (compose: 0:58) (post: 0:257) (render: 0:188) (14:393829 frames) (15:879089)


 


D/SFPerfTracer(  230):        layers: (2:10) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3220)* (StatusBar: 1:23174)* (NavigationBar: 0:16821)* (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:329)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:216) (SurfaceView: 14:1166) 


 


I/SFPerfTracer(  230):       trigger: frame rate (-58.760%) (24.744 fps) (40.414 ms) (22 drops) (19 frames)


 


I/SFPerfTracer(  230):      triggers: (rate: 7260:180218) (compose: 0:58) (post: 0:257) (render: 0:188) (19:393855 frames) (20:879117)


 


D/SFPerfTracer(  230):        layers: (2:10) (DimLayer: 0:103)* (DimLayer: 0:6)* (FocusedStackFrame: 0:3220)* (StatusBar: 0:23174)* (NavigationBar: 0:16821)* (DimLayer: 0:8)* (com.maxelus.shadowgalaxylivewallpaper.WallpaperService: 0:329)* (com.netflix.mediaclient/com.netflix.mediaclient.ui.player.PlayerActivity: 0:216) (SurfaceView: 20:1194) 


 


D/MobileDataStateTracker(  872): default: setPolicyDataEnable(enabled=true)


 


What's the issue?


(This happens with or without a Live Wallpaper, I've already ruled that out)

Reply
Options

13984 Posts

02-02-2016

US

322 Signins

2684 Page Views

  • Posts: 13984
  • Registered: ‎02-02-2016
  • Location: US
  • Views: 2684
  • Message 2 of 2

Re: Excessive delay reading Vsync and SFPerfTracer

2014-03-18, 0:44 AM


@jason.motox: 



You might have better luck getting an answer to this question in the Developers Community....so moving it there :)

Comment in the spirit of COMMUNITY: "Share experiences / expertise, engage in the discussions, and offer advice and suggestions."
Reply
Forum Home

Community Guidelines

Please review our Guidelines before posting.

Learn More

Check out current deals!

Go Shop
X

Save

X

Delete