2012 MBP Sleep and SystemUIServer issues
Hey Guys,
Couple of issues I encounter all too regularly with my 2012 Macbook Pro (non-retiner) and its starting to drive me mad as I cannot seem to find an answer that actually resolves both (searched many times!). I'm also convinced now they are related to some extent too.
There is the ongoing known issue with the MBP's running OS X Mountain Lion (10.8.4) where if plugged in, after a period of a few hours just in sleep mode the system will drop into deep sleep/hibernate until the user opens the lid or removes the power. I was led to believe this was caused by an update by Apple a long time ago, but as this was a new Mac earlier this year for me all of those updates were installed already so the suggestd fix/prevention to cure this won't apply to me without reinstalling from a retail disk. Problem with that is it leaves me open to security holes if I do that.
Below is the log between closing my lid, and opening it again:
05/08/2013 00:31:46.000 kernel[0]: hibernate image path: /var/vm/sleepimage
05/08/2013 00:31:46.000 kernel[0]: AirPort_Brcm43xx::powerChange: System Sleep
05/08/2013 00:31:46.000 kernel[0]: hibernate_alloc_pages act 173778, inact 126419, anon 3591, throt 0, spec 17652, wire 287621, wireinit 231878
05/08/2013 00:31:46.000 kernel[0]: hibernate_setup(0) took 0 ms
05/08/2013 00:31:46.000 kernel[0]: sizeof(IOHibernateImageHeader) == 512
05/08/2013 00:31:46.000 kernel[0]: kern_open_file_for_direct_io(0) took 30 ms
05/08/2013 00:31:46.000 kernel[0]: Opened file /var/vm/sleepimage, size 4294967296, partition base 0x0, maxio 400000 ssd 0
05/08/2013 00:31:46.000 kernel[0]: hibernate image major 1, minor 0, blocksize 512, pollers 5
05/08/2013 00:31:55.000 kernel[0]: hibernate_page_list_setall(preflight 0) start 0xffffff8096ba4000, 0xffffff8096d41000
05/08/2013 00:31:55.000 kernel[0]: hibernate_page_list_setall time: 163 ms
05/08/2013 00:31:55.000 kernel[0]: pages 579795, wire 261425, act 111825, inact 174, cleaned 57 spec 208, zf 3060, throt 0, could discard act 61111 inact 112272 purgeable 1255 spec 17485 cleaned 10923
05/08/2013 00:31:55.000 kernel[0]: hibernate_page_list_setall found pageCount 376749
05/08/2013 00:31:55.000 kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
05/08/2013 00:31:55.000 kernel[0]: IOHibernatePollerOpen(0)
05/08/2013 00:31:55.000 kernel[0]: encryptStart 13230
05/08/2013 00:31:55.000 kernel[0]: bitmap_size 0x1f4dc, previewSize 0x78bc48, writing 374473 pages @ 0x7be354
05/08/2013 00:31:55.000 kernel[0]: encryptEnd 119f4c00
05/08/2013 00:31:55.000 kernel[0]: image1Size 0x16641600, encryptStart1 0x13230, End1 0x119f4c00
05/08/2013 00:31:55.000 kernel[0]: encryptStart 16641600
05/08/2013 00:31:55.000 kernel[0]: encryptEnd 2275d000
05/08/2013 00:31:55.000 kernel[0]: PMStats: Hibernate write took 5284 ms
05/08/2013 00:31:55.000 kernel[0]: all time: 5284 ms, comp bytes: 1534427136 time: 1185 ms 1234 Mb/s, crypt bytes: 498062288 time: 882 ms 538 Mb/s,
05/08/2013 00:31:55.000 kernel[0]: image 578146304 (13%), uncompressed 1534427136 (374616), compressed 567214080 (36%), sum1 6d12e56c, sum2 edcbe5aa
05/08/2013 00:31:55.000 kernel[0]: wired_pages_encrypted 203376, wired_pages_clear 55916, dirty_pages_encrypted 115324
05/08/2013 00:31:55.000 kernel[0]: hibernate_write_image done(0)
05/08/2013 00:31:55.000 kernel[0]: sleep
05/08/2013 04:31:55.000 kernel[0]: Wake reason: EC.SleepTimer (SleepTimer)
05/08/2013 04:31:55.000 kernel[0]: AirPort_Brcm43xx::powerChange: System Wake - Full Wake/ Dark Wake / Maintenance wake
05/08/2013 04:31:55.000 kernel[0]: Previous Sleep Cause: 5
05/08/2013 04:31:55.000 kernel[0]: wlEvent: en1 en1 Link DOWN virtIf = 0
05/08/2013 04:31:55.000 kernel[0]: AirPort: Link Down on en1. Reason 8 (Disassociated because station leaving).
05/08/2013 04:31:55.000 kernel[0]: en1::IO80211Interface::postMessage bssid changed
05/08/2013 04:31:55.000 kernel[0]: en1: 802.11d country code set to 'X3'.
05/08/2013 04:31:55.000 kernel[0]: en1: Supported channels 1 2 3 4 5 6 7 8 9 10 11 12 13 36 40 44 48 52 56 60 64 100 104 108 112 116 120 124 128 132 136 140
05/08/2013 04:31:55.000 kernel[0]: TBT W (1): 0 [x]
05/08/2013 04:31:58.000 kernel[0]: Graphics suppressed 3684 ms
05/08/2013 04:31:59.000 kernel[0]: en1: 802.11d country code set to 'GB'.
05/08/2013 04:31:59.000 kernel[0]: en1: Supported channels 1 2 3 4 5 6 7 8 9 10 11 12 13 36 40 44 48 52 56 60 64 100 104 108 112 116 120 124 128 132 136 140
05/08/2013 04:31:59.000 kernel[0]: MacAuthEvent en1 Auth result for: f8:d1:11:20:79:01 MAC AUTH succeeded
05/08/2013 04:31:59.000 kernel[0]: wlEvent: en1 en1 Link UP virtIf = 0
05/08/2013 04:31:59.000 kernel[0]: AirPort: Link Up on en1
05/08/2013 04:31:59.000 kernel[0]: en1: BSSID changed to f8:d1:11:20:79:01
05/08/2013 04:31:59.000 kernel[0]: en1::IO80211Interface::postMessage bssid changed
05/08/2013 04:31:59.000 kernel[0]: AirPort: RSN handshake complete on en1
05/08/2013 04:32:00.218 airportd[8156]: _doAutoJoin: Already associated to “BeBox”. Bailing on auto-join.
05/08/2013 04:32:00.232 airportd[8156]: _doAutoJoin: Already associated to “BeBox”. Bailing on auto-join.
05/08/2013 04:32:14.000 kernel[0]: hibernate image path: /var/vm/sleepimage
05/08/2013 04:32:14.000 kernel[0]: AirPort_Brcm43xx::powerChange: System Sleep
05/08/2013 04:32:14.000 kernel[0]: hibernate_alloc_pages act 178132, inact 126876, anon 3590, throt 0, spec 18772, wire 287197, wireinit 231878
05/08/2013 04:32:14.000 kernel[0]: hibernate_setup(0) took 0 ms
05/08/2013 04:32:14.000 kernel[0]: sizeof(IOHibernateImageHeader) == 512
05/08/2013 04:32:14.000 kernel[0]: kern_open_file_for_direct_io(0) took 1 ms
05/08/2013 04:32:14.000 kernel[0]: Opened file /var/vm/sleepimage, size 4294967296, partition base 0x0, maxio 400000 ssd 0
05/08/2013 04:32:14.000 kernel[0]: hibernate image major 1, minor 0, blocksize 512, pollers 5
05/08/2013 11:01:40.000 kernel[0]: hibernate_page_list_setall(preflight 0) start 0xffffff8096ba4000, 0xffffff8096d41000
05/08/2013 11:01:41.000 kernel[0]: hibernate_page_list_setall time: 186 ms
05/08/2013 11:01:41.000 kernel[0]: pages 586189, wire 261692, act 114831, inact 170, cleaned 57 spec 220, zf 2179, throt 0, could discard act 61552 inact 112654 purgeable 3301 spec 18610 cleaned 10923
05/08/2013 11:01:41.000 kernel[0]: hibernate_page_list_setall found pageCount 379149
05/08/2013 11:01:41.000 kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
05/08/2013 11:01:41.000 kernel[0]: IOHibernatePollerOpen(0)
05/08/2013 11:01:41.000 kernel[0]: encryptStart 13230
05/08/2013 11:01:41.000 kernel[0]: bitmap_size 0x1f4dc, previewSize 0x78bc48, writing 376873 pages @ 0x7be354
05/08/2013 11:01:41.000 kernel[0]: hibernate_machine_init: state 2, image pages 261488, sum was 596a9975, image1Size 166c6400, conflictCount 1805, nextFree 765e
05/08/2013 11:01:41.000 kernel[0]: hibernate_page_list_discard time: 116 ms, discarded act 61552 inact 112654 purgeable 3301 spec 18610 cleaned 10923
05/08/2013 11:01:41.000 kernel[0]: IOHibernatePollerOpen(), ml_get_interrupts_enabled 0
05/08/2013 11:01:41.000 kernel[0]: IOHibernatePollerOpen(0)
05/08/2013 11:01:41.000 kernel[0]: hibernate_machine_init reading
05/08/2013 11:01:41.000 kernel[0]: PMStats: Hibernate read took 2251 ms
05/08/2013 11:01:41.000 kernel[0]: hibernate_machine_init pagesDone 378945 sum2 20305f3d, time: 2251 ms, comp bytes: 453668864 time: 469 ms 922 Mb/s, crypt bytes: 211245568 time: 93 ms 2151 Mb/s
05/08/2013 11:01:41.000 kernel[0]: Wake reason: EC.LidOpen (User)
So is there ever going to be a fix for this problem?
*************
Next problem (and I think it could be related to the deep sleep issue) is something that happens on a regular basis and requires force quitting and a reboot;
SystemUIServer eats all remaining available RAM and causes the system to grind to a halt until that process is "Force Quit" in Activity Monitor.
Conciously I've noticed it only seems to occur after the system has been in deep sleep/hibernate mode. My system uptime is 2 days since the last SystemUIserver issue.
MacBook Pro (13-inch Mid 2012), OS X Mountain Lion (10.8.4)