[Sony Ericsson Xperia Mini, Mini Pro, Xperia Pro, A] funkyjester:
I have this issue with the alarm clock that is kind of minor but is driving me nuts.

I use the countdown timer a lot, and on many occasions the phone forgets to wake up from deep sleep.

I have included logs of what has been happening on a successful countdown operation, and a failed one.

What I do is fire up the desk clock application, set a 5 minute countdown, then power off the device that goes into deep sleep..

I have broken down the logs into more readable sections and marked a suspicious line with asterisks in the log for the failed attempt.

I am using cm-12.1-20150831-UNOFFICIAL-LegacyXperia-haida.zip

Any help on what might be the problem or what I can try out to fix it is greatly appreciated.

success.txt: set the countdown timer for 5 minutes, it wakes up and rings after 5 minutes

09-27 01:47:52.835 V/TimerReceiver(12744): Received intent Intent { act=start_timer flg=0x10000010 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:47:52.838 D/TimerReceiver(12744): Setting times up to 50012183

09-27 01:47:54.324 I/PowerManagerService( 539): Going to sleep due to power button (uid 1000)...
09-27 01:47:54.327 I/PowerManagerService( 539): Sleeping (uid 1000)...
09-27 01:47:54.738 E/WifiStateMachine( 539): cancelDelayedScan -> 396
09-27 01:47:54.930 I/DisplayManagerService( 539): Display device changed: DisplayDeviceInfo{"Built-in Screen": uniqueId="local:0", 480 x 854, 60.0 fps, supportedRefreshRates [60.0], density 240, 160.421 x 159.497 dpi, appVsyncOff 0, presDeadline 17666667, touch INTERNAL, rotation 0, type BUILT_IN, state OFF, FLAG_DEFAULT_DISPLAY, FLAG_ROTATES_WITH_CONTENT, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS}
09-27 01:47:54.932 V/ActivityManager( 539): Display changed displayId=0
09-27 01:47:54.939 D/SurfaceFlinger( 212): Set power mode=0, type=0 flinger=0x13075e0
09-27 01:47:54.941 D/qdhwcomposer( 212): hwc_blank: Blanking display: 0
09-27 01:47:55.405 D/qdhwcomposer( 212): hwc_blank: Done blanking display: 0
09-27 01:47:55.408 D/SurfaceControl( 539): Excessive delay in setPowerMode(): 4773ms
09-27 01:47:56.599 V/TimerReceiver(12744): Received intent Intent { act=notif_in_use_show flg=0x10 cmp=com.android.deskclock/.timer.TimerReceiver }
09-27 01:47:58.011 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:47:58.121 I/art ( 539): Background sticky concurrent mark sweep GC freed 18015(1012KB) AllocSpace objects, 7(208KB) LOS objects, 7% free, 15MB/17MB, paused 10.033ms total 83.559ms
09-27 01:48:24.011 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:48:24.298 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:52:53.011 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:52:53.019 W/art ( 539): Long monitor contention event with owner method=void com.android.server.power.PowerManagerService.releaseWakeLockInternal(android.os.IBinder, int) from PowerManagerService.java:916 waiters=0 for 544ms
09-27 01:52:53.024 V/TimerReceiver(12744): Received intent Intent { act=notif_in_use_show flg=0x14 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:52:53.024 V/TimerReceiver(12744): Will not show notification for timer already expired.
09-27 01:52:53.324 I/art ( 807): Background sticky concurrent mark sweep GC freed 15052(606KB) AllocSpace objects, 0(0B) LOS objects, 5% free, 12MB/13MB, paused 884us total 255.803ms

09-27 01:52:53.339 V/TimerReceiver(12744): Received intent Intent { act=times_up flg=0x10000014 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:52:53.339 D/TimerReceiver(12744): playing ringtone
09-27 01:52:53.347 I/Timeline(12744): Timeline: Activity_launch_request id:com.android.deskclock time:21142171
09-27 01:52:53.348 I/ActivityManager( 539): START u0 {flg=0x10040000 cmp=com.android.deskclock/.timer.TimerAlertFullScreen} from uid 10041 on display 0
09-27 01:52:53.355 V/WindowManager( 539): addAppToken: AppWindowToken{26919c7c token=Token{3c2c376f ActivityRecord{12036a4e u0 com.android.deskclock/.timer.TimerAlertFullScreen t135}}} to stack=1 task=135 at 0
09-27 01:52:53.361 V/ActivityManager( 539): Display changed displayId=0
09-27 01:52:53.387 V/TimerReceiver(12744): no next times up

09-27 01:52:53.397 V/AlarmClock(12744): TimerRingService.play()
09-27 01:52:53.411 E/MediaPlayer-JNI(12744): QCMediaPlayer mediaplayer NOT present
09-27 01:52:53.417 D/AwesomePlayer( 243): Before reset_l
09-27 01:52:53.418 D/AwesomePlayer( 243): printFileName fd(19) -> /system/app/DeskClock/DeskClock.apk
09-27 01:52:53.434 I/FFmpegExtractor( 243): android-source:0x1f3bc00
09-27 01:52:53.443 I/FFMPEG ( 243): Input #0, ogg, from 'android-source:0x1f3bc00':
09-27 01:52:53.443 I/FFMPEG ( 243): Duration: 00:00:10.49, start: 0.000000, bitrate: 133 kb/s
09-27 01:52:53.443 I/FFMPEG ( 243): Stream #0:0: Audio: vorbis, 48000 Hz, stereo, fltp, 128 kb/s
09-27 01:52:53.443 I/FFMPEG ( 243): Metadata:
09-27 01:52:53.443 I/FFMPEG ( 243): ANDROID_LOOP : true
09-27 01:52:53.443 I/FFMPEG ( 243): TITLE : Timer_Expire
09-27 01:52:53.443 D/FFmpegExtractor( 243): FFmpegExtrator, url: android-source:0x1f3bc00, format_name: ogg, format_long_name: Ogg
09-27 01:52:53.443 I/FFmpegExtractor( 243): adjust mime(application/ogg -> audio/vorbis)
09-27 01:52:53.443 D/FFmpegExtractor( 243): suppoted codec(vorbis) by official Stagefright
09-27 01:52:53.443 D/FFmpegExtractor( 243): ffmpeg detected media content as 'audio/vorbis' with confidence 0.08
09-27 01:52:53.450 D/ExtendedUtils( 243): extended extractor not needed, return default
09-27 01:52:53.452 W/Utils ( 243): bool android::canOffloadStream(const android::sp<android::MetaData>&, bool, const android::sp<android::MetaData>&, bool, audio_stream_type_t) No sample bit depth info in meta data
09-27 01:52:53.452 D/AwesomePlayer( 243): Use tunnel player only for AUDIO_STREAM_MUSIC
09-27 01:52:53.454 D/OMXCodec( 243): Successfully allocated OMX node 'OMX.google.vorbis.decoder'
09-27 01:52:53.454 I/OMXCodec( 243): Codec outputs a different number of channels than the input stream contains (contains 2 channels, codec outputs 1 channels).
09-27 01:52:53.454 I/OMXCodec( 243): Codec outputs at different sampling rate than what the input stream contains (contains data at 48000 Hz, codec outputs 44100 Hz)
09-27 01:52:53.454 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 8192 on input port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f3c928 on input port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1fc7240 on input port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f53960 on input port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4ccc8 on input port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 32768 on output port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f534a0 on output port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f3f3f0 on output port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4a7a8 on output port
09-27 01:52:53.455 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f45a98 on output port
09-27 01:52:53.456 E/MediaPlayer(12744): Should have subtitle controller already set
09-27 01:52:53.457 I/MediaFocusControl( 539): AudioFocus requestAudioFocus() from android.media.AudioManager@8686c9ccom.android.deskclock.TimerRingService@235f6a5 req=2flags=0x0
09-27 01:52:53.459 I/AudioPlayer( 243): start of Playback, useOffload 0
09-27 01:52:53.463 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 32768 on output port
09-27 01:52:53.463 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4cd48 on output port
09-27 01:52:53.463 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f42fe0 on output port
09-27 01:52:53.463 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f459e8 on output port
09-27 01:52:53.463 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4a718 on output port
09-27 01:52:53.464 E/AudioTrack( 243): AudioTrack::set : Exit
09-27 01:52:53.877 W/ActivityManager( 539): Activity pause timeout for ActivityRecord{12036a4e u0 com.android.deskclock/.timer.TimerAlertFullScreen t135}
09-27 01:52:54.715 V/WindowManager( 539): Adding window Window{3c2fdd5a u0 com.android.deskclock/com.android.deskclock.timer.TimerAlertFullScreen} at 4 of 9 (after Window{2dceb2dd u0 com.android.deskclock/com.android.deskclock.DeskClock})
09-27 01:52:54.719 E/MediaPlayer(12744): Should have subtitle controller already set
09-27 01:52:54.903 V/Clock-StaggeredGridView(12744): [fillDown] position: 0 id: 0 childLeft: 0 childTop: 0 column: 0 childHeight:552
09-27 01:52:54.904 V/Clock-StaggeredGridView(12744): starting
09-27 01:52:54.906 V/TimerReceiver(12744): Received intent Intent { act=notif_times_up_cancel flg=0x10 cmp=com.android.deskclock/.timer.TimerReceiver }
09-27 01:52:54.907 V/TimerReceiver(12744): Canceling times-up notification for Timer #0

fail.txt: set the countdown timer for 5 minutes, something happens at 5 minutes but no ring, phone wakes up after 16 minutes apparently from some other event

09-27 01:14:38.447 V/TimerReceiver(12401): Received intent Intent { act=start_timer flg=0x10000010 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:14:38.450 D/TimerReceiver(12401): Setting times up to 48017800

09-27 01:14:39.052 I/ActivityManager( 539): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.cyanogenmod.trebuchet/com.android.launcher3.Launcher} from uid 1000 on display 0
09-27 01:14:39.193 V/TimerReceiver(12401): Received intent Intent { act=notif_in_use_show flg=0x10 cmp=com.android.deskclock/.timer.TimerReceiver }
09-27 01:14:39.628 I/Timeline( 1012): Timeline: Activity_idle id: android.os.BinderProxy@3e58a255 time:20106052
09-27 01:14:39.651 W/InputMethodManagerService( 539): Starting input on non-focused client com.android.internal.view.IInputMethodClient$Stub$Proxy@5e793b4 (uid=10041 pid=12401)
09-27 01:14:39.768 W/OpenGLRenderer( 807): Incorrectly called buildLayer on View: TextView, destroying layer...
09-27 01:14:39.877 I/Timeline( 539): Timeline: Activity_windows_visible id: ActivityRecord{3355505c u0 com.cyanogenmod.trebuchet/com.android.launcher3.Launcher t55} time:20106301
09-27 01:14:40.090 W/OpenGLRenderer( 1012): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
09-27 01:14:40.090 W/OpenGLRenderer( 1012): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...

09-27 01:14:40.676 I/PowerManagerService( 539): Going to sleep due to power button (uid 1000)...
09-27 01:14:40.678 I/PowerManagerService( 539): Sleeping (uid 1000)...
09-27 01:14:40.819 E/WifiStateMachine( 539): cancelDelayedScan -> 390
09-27 01:14:41.275 I/DisplayManagerService( 539): Display device changed: DisplayDeviceInfo{"Built-in Screen": uniqueId="local:0", 480 x 854, 60.0 fps, supportedRefreshRates [60.0], density 240, 160.421 x 159.497 dpi, appVsyncOff 0, presDeadline 17666667, touch INTERNAL, rotation 0, type BUILT_IN, state OFF, FLAG_DEFAULT_DISPLAY, FLAG_ROTATES_WITH_CONTENT, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS}
09-27 01:14:41.276 V/ActivityManager( 539): Display changed displayId=0
09-27 01:14:41.280 D/SurfaceFlinger( 212): Set power mode=0, type=0 flinger=0x13075e0
09-27 01:14:41.284 D/qdhwcomposer( 212): hwc_blank: Blanking display: 0
09-27 01:14:41.747 D/qdhwcomposer( 212): hwc_blank: Done blanking display: 0
09-27 01:14:41.750 D/SurfaceControl( 539): Excessive delay in setPowerMode(): 474ms
09-27 01:14:42.205 D/PowerManagerService-JNI( 539): Excessive delay in autosuspend_enable() while turning screen off: 455ms
09-27 01:15:11.011 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:19:39.021 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:19:39.077 V/TimerReceiver(12401): Received intent Intent { act=notif_in_use_show flg=0x14 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:19:39.077 V/TimerReceiver(12401): Will not show notification for timer already expired.

09-27 01:19:39.191 V/TimerReceiver(12401): Received intent Intent { act=times_up flg=0x10000014 cmp=com.android.deskclock/.timer.TimerReceiver (has extras) }
09-27 01:19:39.196 D/TimerReceiver(12401): playing ringtone
09-27 01:19:39.221 I/Timeline(12401): Timeline: Activity_launch_request id:com.android.deskclock time:20113134
09-27 01:19:39.223 I/ActivityManager( 539): START u0 {flg=0x10040000 cmp=com.android.deskclock/.timer.TimerAlertFullScreen} from uid 10041 on display 0
09-27 01:19:39.229 V/WindowManager( 539): addAppToken: AppWindowToken{5b49aaa token=Token{178cb995 ActivityRecord{1805a54c u0 com.android.deskclock/.timer.TimerAlertFullScreen t133}}} to stack=1 task=133 at 0
09-27 01:19:39.234 V/ActivityManager( 539): Display changed displayId=0
****** 09-27 01:19:39.762 W/ActivityManager( 539): Activity pause timeout for ActivityRecord{1805a54c u0 com.android.deskclock/.timer.TimerAlertFullScreen t133}
09-27 01:19:40.462 V/TimerReceiver(12401): no next times up

09-27 01:30:53.021 I/AKMD2 ( 247): akmd2 : Suspended.
09-27 01:30:53.053 V/BackupManagerService( 539): Running a backup pass
09-27 01:30:53.090 V/BackupManagerService( 539): Backup requested but nothing pending

09-27 01:30:53.482 V/AlarmClock(12401): TimerRingService.play()
09-27 01:30:53.496 E/MediaPlayer-JNI(12401): QCMediaPlayer mediaplayer NOT present
09-27 01:30:53.500 D/AwesomePlayer( 243): Before reset_l
09-27 01:30:53.501 D/AwesomePlayer( 243): printFileName fd(19) -> /system/app/DeskClock/DeskClock.apk
09-27 01:30:53.514 I/FFmpegExtractor( 243): android-source:0x1f44610
09-27 01:30:53.523 I/FFMPEG ( 243): Input #0, ogg, from 'android-source:0x1f44610':
09-27 01:30:53.523 I/FFMPEG ( 243): Duration: 00:00:10.49, start: 0.000000, bitrate: 133 kb/s
09-27 01:30:53.523 I/FFMPEG ( 243): Stream #0:0: Audio: vorbis, 48000 Hz, stereo, fltp, 128 kb/s
09-27 01:30:53.523 I/FFMPEG ( 243): Metadata:
09-27 01:30:53.523 I/FFMPEG ( 243): ANDROID_LOOP : true
09-27 01:30:53.523 I/FFMPEG ( 243): TITLE : Timer_Expire
09-27 01:30:53.523 D/FFmpegExtractor( 243): FFmpegExtrator, url: android-source:0x1f44610, format_name: ogg, format_long_name: Ogg
09-27 01:30:53.523 I/FFmpegExtractor( 243): adjust mime(application/ogg -> audio/vorbis)
09-27 01:30:53.523 D/FFmpegExtractor( 243): suppoted codec(vorbis) by official Stagefright
09-27 01:30:53.523 D/FFmpegExtractor( 243): ffmpeg detected media content as 'audio/vorbis' with confidence 0.08
09-27 01:30:53.530 D/ExtendedUtils( 243): extended extractor not needed, return default
09-27 01:30:53.532 W/Utils ( 243): bool android::canOffloadStream(const android::sp<android::MetaData>&, bool, const android::sp<android::MetaData>&, bool, audio_stream_type_t) No sample bit depth info in meta data
09-27 01:30:53.532 D/AwesomePlayer( 243): Use tunnel player only for AUDIO_STREAM_MUSIC
09-27 01:30:53.534 D/OMXCodec( 243): Successfully allocated OMX node 'OMX.google.vorbis.decoder'
09-27 01:30:53.534 I/OMXCodec( 243): Codec outputs a different number of channels than the input stream contains (contains 2 channels, codec outputs 1 channels).
09-27 01:30:53.534 I/OMXCodec( 243): Codec outputs at different sampling rate than what the input stream contains (contains data at 48000 Hz, codec outputs 44100 Hz)
09-27 01:30:53.534 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 8192 on input port
09-27 01:30:53.534 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f3c928 on input port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f3c7e0 on input port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f3c880 on input port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4ccc8 on input port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 32768 on output port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f53990 on output port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f534e8 on output port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4a468 on output port
09-27 01:30:53.535 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4bc68 on output port
09-27 01:30:53.536 E/MediaPlayer(12401): Should have subtitle controller already set
09-27 01:30:53.537 I/MediaFocusControl( 539): AudioFocus requestAudioFocus() from android.media.AudioManager@6ddb688com.android.deskclock.TimerRingService@2f345821 req=2flags=0x0
09-27 01:30:53.539 I/AudioPlayer( 243): start of Playback, useOffload 0
09-27 01:30:53.543 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocating 4 buffers of size 32768 on output port
09-27 01:30:53.543 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f4cd20 on output port
09-27 01:30:53.543 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f53510 on output port
09-27 01:30:53.543 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f53978 on output port
09-27 01:30:53.543 I/OMXCodec( 243): [OMX.google.vorbis.decoder] allocated buffer 0x1f35208 on output port
09-27 01:30:53.544 E/AudioTrack( 243): AudioTrack::set : Exit
09-27 01:30:53.642 V/WindowManager( 539): Adding window Window{38dca38 u0 com.android.deskclock/com.android.deskclock.timer.TimerAlertFullScreen} at 4 of 9 (after Window{5d57fd8 u0 com.android.deskclock/com.android.deskclock.DeskClock})
09-27 01:30:53.658 E/MediaPlayer(12401): Should have subtitle controller already set
09-27 01:30:53.799 V/Clock-StaggeredGridView(12401): [fillDown] position: 0 id: 0 childLeft: 0 childTop: 0 column: 0 childHeight:552
09-27 01:30:53.800 V/Clock-StaggeredGridView(12401): starting
09-27 01:30:53.814 V/TimerReceiver(12401): Received intent Intent { act=notif_times_up_cancel flg=0x10 cmp=com.android.deskclock/.timer.TimerReceiver }
09-27 01:30:53.816 V/TimerReceiver(12401): Canceling times-up notification for Timer #0