[Nexus 5 Q&A, Help & Troubleshooting] c3k:
After receiving my new phone I put my custom sounds in internal storage in Alarms/Notifications/Ringtones folder and the system show them and let me use it.

It works, but sometimes (estimate 5-10%) no sound is playing, and is pretty annoying, especially with morning alarm! (I start to use a stock sound for that)

I'm running stock rooted rom.

Nobody notice this?

Seems other kitkat users have this problem.

I've a lot of application installed, maybe one of those interferes...just tried to disable "call annuncer"...let see.

It just happen with an sms (below the logcat) and as bonus thing when I open ringtones picker none of those is playing when clicked:

11-27 09:06:38.048 D/dalvikvm(1198): GC_CONCURRENT freed 576K, 5% free 17947K/18848K, paused 6ms+1ms, total 22ms
11-27 09:06:38.048 D/dalvikvm(1198): WAIT_FOR_CONCURRENT_GC blocked 16ms
11-27 09:06:38.048 D/dalvikvm(1198): WAIT_FOR_CONCURRENT_GC blocked 18ms
11-27 09:06:38.148 I/GCoreUlr(1198): Successfully inserted location
11-27 09:06:38.148 I/GCoreUlr(1198): Not calling LocationReportingService, hasMoved: false, elapsed millis: 469336, request: Phone
11-27 09:06:38.488 D/dalvikvm(1198): GC_CONCURRENT freed 462K, 5% free 18002K/18848K, paused 2ms+2ms, total 16ms
11-27 09:06:38.488 D/dalvikvm(1198): WAIT_FOR_CONCURRENT_GC blocked 9ms
11-27 09:06:38.548 D/dalvikvm(1198): GC_CONCURRENT freed 534K, 6% free 17893K/18848K, paused 1ms+1ms, total 19ms
11-27 09:06:44.758 I/ActivityManager(750): Start proc com.zegoggles.smssync for broadcast com.zegoggles.smssync/.receiver.SmsBroadcastReceiver: pid=27801 uid=10137 gids={50137, 3003, 1028, 1015}
11-27 09:06:44.768 I/dalvikvm(27801): Enabling JNI app bug workarounds for target SDK version 10...
11-27 09:06:44.788 V/8sms/SmsReceiverServic(4556): onStart: #1 mResultCode: -1 = Activity.RESULT_OK
11-27 09:06:44.808 D/ACRA (27801): ACRA is enabled for com.zegoggles.smssync, intializing...
11-27 09:06:44.818 D/ACRA (27801): Using default Report Fields
11-27 09:06:44.818 D/ACRA (27801): Looking for error files in /data/data/com.zegoggles.smssync/files
11-27 09:06:44.848 D/dalvikvm(27801): GC_CONCURRENT freed 257K, 2% free 16532K/16824K, paused 3ms+0ms, total 13ms
11-27 09:06:44.858 I/ActivityManager(750): Killing 26577:com.ideashower.readitlater.pro/u0a170 (adj 15): empty #17
11-27 09:06:44.888 D/dalvikvm(1054): GC_CONCURRENT freed 358K, 3% free 17742K/18272K, paused 1ms+1ms, total 13ms
11-27 09:06:45.098 D/8sms/SmsReceiverServic(4556): handleSmsReceived messageUri: content://sms/288 threadId: 49
11-27 09:06:45.128 D/dalvikvm(4556): GC_CONCURRENT freed 863K, 5% free 20693K/21676K, paused 1ms+2ms, total 18ms
11-27 09:06:45.128 D/dalvikvm(4556): WAIT_FOR_CONCURRENT_GC blocked 16ms
11-27 09:06:45.138 I/ActivityManager(750): START u0 {flg=0x34800000 cmp=com.thinkleft.eightyeightsms.mms/.quickmessage.QuickMessagePopup (has extras)} from pid 4556
11-27 09:06:45.228 I/ActivityManager(750): Displayed com.thinkleft.eightyeightsms.mms/.quickmessage.QuickMessagePopup: +80ms (total +83ms)
11-27 09:06:48.126 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:48.126 D/skia (17882): --- SkImageDecoder::Factory returned null

11-27 09:06:49.688 I/PowerManagerService(750): Waking up from sleep...
11-27 09:06:49.698 V/KeyguardServiceDelegate(750): onScreenTurnedOn(showListener = com.android.internal.policy.impl.PhoneWindowManager$17@43161d98)
11-27 09:06:49.718 D/SurfaceFlinger(178): Screen acquired, type=0 flinger=0xb7c81450
11-27 09:06:49.718 D/qdhwcomposer(178): hwc_blank: Unblanking display: 0
11-27 09:06:49.778 V/KeyguardServiceDelegate(750): **** SHOWN CALLED ****
11-27 09:06:49.778 I/WindowManager(750): Lock screen displayed!
11-27 09:06:49.948 D/qdhwcomposer(178): hwc_blank: Done unblanking display: 0
11-27 09:06:49.948 D/SurfaceControl(750): Excessive delay in unblankDisplay() while turning screen on: 230ms
11-27 09:06:50.008 D/YouTube MDX(13554): Recieved intent android.intent.action.SCREEN_ON
11-27 09:06:50.008 D/dalvikvm(911): GC_CONCURRENT freed 5026K, 48% free 31547K/59548K, paused 3ms+13ms, total 45ms
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:50.028 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:51.138 D/YouTube (13554): apps.youtube.app.prefetch.g.onReceive:367 Received: android.intent.action.USER_PRESENT
11-27 09:06:51.158 D/PhoneStatusBar(911): disable: < expand icons alerts ticker system_info back* home* recent* clock search >
11-27 09:06:52.178 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:52.178 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:06:53.278 W/IInputConnectionWrapper(4556): showStatusIcon on inactive InputConnection
11-27 09:06:53.298 D/8sms/Mms(4556): [2194] markAsRead: update read/seen for thread uri: content://mms-sms/conversations/49
11-27 09:06:53.538 W/SurfaceFlinger(178): couldn't log to binary event log: overflow.
11-27 09:06:54.248 D/dalvikvm(750): GC_FOR_ALLOC freed 8087K, 27% free 47752K/65148K, paused 73ms, total 73ms
11-27 09:06:54.318 I/ActivityManager(750): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.teslacoilsw.launcher/com.android.launcher2.Launcher} from pid 750
11-27 09:06:54.418 I/dalvikvm(17833): Jit: resizing JitTable from 4096 to 8192
11-27 09:06:55.218 I/ActivityManager(750): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.thinkleft.eightyeightsms.mms/.ui.ConversationList_0 bnds=[846,1567][1050,1765]} from pid 17833
11-27 09:06:55.308 D/dalvikvm(750): GC_FOR_ALLOC freed 6092K, 29% free 46793K/65148K, paused 90ms, total 90ms
11-27 09:06:55.398 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:55.398 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:55.448 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:55.768 D/dalvikvm(4465): GC_EXPLICIT freed 53K, 3% free 16479K/16884K, paused 3ms+2ms, total 22ms
11-27 09:06:55.898 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:55.908 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:55.928 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:57.388 I/ActivityManager(750): START u0 {cmp=com.thinkleft.eightyeightsms.mms/.ui.MessagingPreferenceActivity} from pid 4556
11-27 09:06:57.448 D/dalvikvm(4556): GC_FOR_ALLOC freed 604K, 5% free 21132K/22104K, paused 17ms, total 17ms
11-27 09:06:57.468 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:57.478 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:57.478 W/Resources(4556): Converting to string: TypedValue{t=0x10/d=0x500 a=-1}
11-27 09:06:57.488 W/Resources(4556): Converting to string: TypedValue{t=0x10/d=0x3c0 a=-1}
11-27 09:06:57.488 W/Resources(4556): Converting to string: TypedValue{t=0x10/d=0x12c a=-1}
11-27 09:06:57.488 W/Resources(4556): Converting to string: TypedValue{t=0x10/d=0x2 a=-1}
11-27 09:06:57.488 W/Resources(4556): Converting to string: TypedValue{t=0x10/d=0x2 a=-1}
11-27 09:06:57.518 V/8sms/TransactionSettin(4556): APN setting: MMSC: http://mms.tim.it/servlets/mms looked for: current IS NOT NULL
11-27 09:06:57.528 V/8sms/TransactionSettin(4556): APN setting: MMSC: http://mms.tim.it/servlets/mms looked for: current IS NOT NULL
11-27 09:06:57.558 D/Ringtone(4556): Successfully created local player
11-27 09:06:57.578 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:06:57.578 E/MediaPlayer(4556): Should have subtitle controller already set
11-27 09:06:57.698 I/ActivityManager(750): Displayed com.thinkleft.eightyeightsms.mms/.ui.MessagingPreferenceActivity: +274ms
11-27 09:06:58.028 W/SurfaceFlinger(178): couldn't log to binary event log: overflow.
11-27 09:07:02.478 I/ActivityManager(750): START u0 {act=android.intent.action.RINGTONE_PICKER cmp=com.android.providers.media/.RingtonePickerActivity (has extras)} from pid 4556
11-27 09:07:02.638 I/Adreno-EGL(25240): <qeglDrvAPI_eglInitialize:320>: EGL 1.4 QUALCOMM build: (CL3776187)
11-27 09:07:02.638 I/Adreno-EGL(25240): OpenGL ES Shader Compiler Version:
11-27 09:07:02.638 I/Adreno-EGL(25240): Build Date: 10/15/13 Tue
11-27 09:07:02.638 I/Adreno-EGL(25240): Local Branch:
11-27 09:07:02.638 I/Adreno-EGL(25240): Remote Branch: partner/upstream
11-27 09:07:02.638 I/Adreno-EGL(25240): Local Patches:
11-27 09:07:02.638 I/Adreno-EGL(25240): Reconstruct Branch:
11-27 09:07:02.648 D/OpenGLRenderer(25240): Enabling debug mode 0
11-27 09:07:02.738 I/ActivityManager(750): Displayed com.android.providers.media/.RingtonePickerActivity: +230ms
11-27 09:07:02.768 D/dalvikvm(25240): GC_CONCURRENT freed 232K, 2% free 17882K/18152K, paused 1ms+6ms, total 15ms
11-27 09:07:05.738 D/Ringtone(25240): Successfully created local player
11-27 09:07:05.738 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:09.228 D/Ringtone(25240): Successfully created local player
11-27 09:07:09.228 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:11.358 D/Ringtone(25240): Successfully created local player
11-27 09:07:11.358 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:13.928 D/Ringtone(25240): Successfully created local player
11-27 09:07:13.928 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:15.118 D/Ringtone(25240): Successfully created local player
11-27 09:07:15.118 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:16.338 D/Ringtone(25240): Successfully created local player
11-27 09:07:16.338 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:17.098 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:17.108 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:17.188 D/dalvikvm(17882): GC_CONCURRENT freed 3036K, 11% free 26221K/29428K, paused 3ms+7ms, total 49ms
11-27 09:07:18.838 D/Ringtone(25240): Successfully created local player
11-27 09:07:18.838 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.318 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.328 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.328 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:19.608 D/Ringtone(25240): Successfully created local player
11-27 09:07:19.608 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:21.468 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:21.468 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:21.638 D/audio_hw_primary(183): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=2
11-27 09:07:21.648 D/audio_hw_primary(183): select_devices: out_snd_device(2: speaker) in_snd_device(0: )
11-27 09:07:22.528 D/Ringtone(25240): Successfully created local player
11-27 09:07:22.538 D/audio_hw_primary(183): out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=2
11-27 09:07:22.538 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:23.278 D/Ringtone(25240): Successfully created local player
11-27 09:07:23.278 E/MediaPlayer(25240): Should have subtitle controller already set
11-27 09:07:24.358 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:24.358 D/skia (17882): --- SkImageDecoder::Factory returned null
11-27 09:07:24.428 D/dalvikvm(750): GC_FOR_ALLOC freed 5348K, 27% free 47675K/65148K, paused 70ms, total 70ms
11-27 09:07:24.478 D/Ringtone(4556): Successfully created local player
11-27 09:07:24.488 E/MediaPlayer(4556): Should have subtitle controller already set
11-27 09:07:24.488 W/InputMethodManagerService(750): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@435e8070 attribute=null, token = android.os.BinderProxy@43091a80
11-27 09:07:25.478 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:25.478 D/dalvikvm(1054): GC_CONCURRENT freed 385K, 3% free 17754K/18272K, paused 2ms+2ms, total 21ms
11-27 09:07:25.488 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:25.498 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:25.788 W/SurfaceFlinger(178): couldn't log to binary event log: overflow.
11-27 09:07:25.978 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:25.988 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.018 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.488 D/dalvikvm(750): GC_FOR_ALLOC freed 5139K, 27% free 47636K/65148K, paused 75ms, total 75ms
11-27 09:07:26.508 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.538 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.548 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.588 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.598 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.648 D/8sms/Mms(4556): [1] mDeleteObsoleteThreadsRunnable calling asyncDeleteObsoleteThreads
11-27 09:07:26.648 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.658 D/8sms/MessagingNotificat(4556): cancelNotification
11-27 09:07:26.658 D/8sms/Mms(4556): [1] onQueryComplete finished DELETE_OBSOLETE_THREADS_TOKEN
11-27 09:07:26.738 D/dalvikvm(22825): GC_CONCURRENT freed 422K, 3% free 17315K/17772K, paused 1ms+2ms, total 15ms
11-27 09:07:26.998 W/SurfaceFlinger(178): couldn't log to binary event log: overflow.
11-27 09:07:27.348 D/dalvikvm(750): GC_FOR_ALLOC freed 4353K, 26% free 48465K/65148K, paused 71ms, total 71ms
11-27 09:07:27.398 D/dalvikvm(911): GC_FOR_ALLOC freed 11017K, 59% free 24844K/59548K, paused 24ms, total 24ms
11-27 09:07:27.398 I/ActivityManager(750): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.teslacoilsw.launcher/com.android.launcher2.Launcher} from pid 750
11-27 09:07:27.908 W/SurfaceFlinger(178): couldn't log to binary event log: overflow.
11-27 09:07:31.198 I/ActivityManager(750): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 pkg=com.nolanlawson.logcat cmp=com.nolanlawson.logcat/.LogcatActivity bnds=[180,705][396,951]} from pid 17833
11-27 09:07:31.208 I/ActivityManager(750): Start proc com.nolanlawson.logcat for activity com.nolanlawson.logcat/.LogcatActivity: pid=28080 uid=10305 gids={50305, 1028, 1015}
11-27 09:07:31.218 I/dalvikvm(28080): Enabling JNI app bug workarounds for target SDK version 10...
11-27 09:07:31.278 V/PhoneStatusBar(911): setLightsOn(true)
11-27 09:07:31.308 D/dalvikvm(28080): GC_CONCURRENT freed 73K, 1% free 16803K/16916K, paused 2ms+1ms, total 16ms
11-27 09:07:31.308 D/dalvikvm(28080): WAIT_FOR_CONCURRENT_GC blocked 8ms
11-27 09:07:31.348 D/dalvikvm(28080): GC_CONCURRENT freed 70K, 1% free 17185K/17292K, paused 2ms+2ms, total 17ms
11-27 09:07:31.348 D/dalvikvm(28080): WAIT_FOR_CONCURRENT_GC blocked 7ms
11-27 09:07:31.438 I/ActivityManager(750): Displayed com.nolanlawson.logcat/.LogcatActivity: +234ms
11-27 09:07:31.598 D/dalvikvm(4465): GC_EXPLICIT freed 19K, 3% free 16462K/16884K, paused 1ms+1ms, total 20ms
11-27 09:07:31.608 D/dalvikvm(28080): GC_CONCURRENT freed 147K, 2% free 17424K/17608K, paused 2ms+0ms, total 12ms
11-27 09:07:31.668 D/dalvikvm(28080): GC_CONCURRENT freed 446K, 3% free 17421K/17904K, paused 1ms+4ms, total 24ms
11-27 09:07:31.698 D/AndroidRuntime(28113):
11-27 09:07:31.698 D/AndroidRuntime(28113): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
11-27 09:07:31.698 D/AndroidRuntime(28113): CheckJNI is OFF
11-27 09:07:31.708 D/dalvikvm(28113): Trying to load lib libjavacore.so 0x0
11-27 09:07:31.708 D/dalvikvm(28113): Added shared lib libjavacore.so 0x0
11-27 09:07:31.708 D/dalvikvm(28113): Trying to load lib libnativehelper.so 0x0
11-27 09:07:31.708 D/dalvikvm(28113): Added shared lib libnativehelper.so 0x0
11-27 09:07:31.708 D/dalvikvm(28113): No JNI_OnLoad found in libnativehelper.so 0x0, skipping init
11-27 09:07:31.738 D/dalvikvm(28113): Note: class Landroid/app/ActivityManagerNative; has 179 unimplemented (abstract) methods
11-27 09:07:31.778 D/dalvikvm(28080): GC_CONCURRENT freed 436K, 3% free 17423K/17904K, paused 2ms+2ms, total 13ms
11-27 09:07:31.838 D/dalvikvm(28080): GC_CONCURRENT freed 432K, 3% free 17420K/17904K, paused 1ms+0ms, total 10ms