MBP Retina appears to hibernate but doesn't enter low power
My MBP (Retina, 15-inch, Early 2013) was recently upgraded to Yosemite and had it's logic board replaced (due to errant GPU).
Recently I noted that it is notably warm in the morning (having put it to sleep the night before), with the battery draining to ~50% in 8-10 hours. Reviewing the syslog, it appears to correct sleep, and then around an hour later it wakes to enter hibernate. From my reading of the syslog, it looks like everything is working fine, only it clearly doesn't enter low power mode.
I have tried resetting power saver setting to defaults, resetting SMC and NVRAM/PRAM per OS X: When your Mac doesn't sleep or wake - Apple Support, to no avail. Relevant entries from syslog:
May 27 18:04:23 XXX kernel[0] <Notice>: AppleThunderboltNHIType2::waitForOk2Go2Sx - retries = 6
May 27 19:14:24 XXX kernel[0] <Notice>: Wake reason: EC.SleepTimer (SleepTimer)
May 27 19:14:24 XXX kernel[0] <Notice>: Previous sleep cause: 5
May 27 19:14:24 XXX kernel[0] <Notice>: AppleThunderboltNHIType2::prePCIWake - power up complete - took 1 us
May 27 19:14:24 XXX kernel[0] <Notice>: AppleThunderboltGenericHAL::earlyWake - complete - took 1 milliseconds
May 27 19:14:24 XXX.local discoveryd[80] <Notice>: Basic DNSResolver UDNSServer:: PowerState is DarkWake
May 27 19:14:24 XXX kernel[0] <Notice>: IOThunderboltSwitch<0xffffff804be53000>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 11 unplug = 0
May 27 19:14:24 XXX kernel[0] <Notice>: IOThunderboltSwitch<0xffffff804be53000>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 12 unplug = 0
May 27 19:14:24 XXX kernel[0] <Notice>: TBT W (2): 0x0100 [x]
May 27 19:14:24 XXX kernel[0] <Notice>: en0: channel changed to 1
May 27 19:14:24 XXX kernel[0] <Notice>: AirPort: Link Up on awdl0
May 27 19:14:24 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::setAwdlOperatingMode Setting the AWDL operation mode from AUTO to SUSPENDED
May 27 19:14:24 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::setAwdlSuspendedMode() Suspending AWDL, enterQuietMode(true)
May 27 19:14:24 XXX kernel[0] <Notice>: en0: 802.11d country code set to 'US'.
May 27 19:14:24 XXX kernel[0] <Notice>: en0: Supported channels 1 2 3 4 5 6 7 8 9 10 11 36 40 44 48 52 56 60 64 100 104 108 112 116 120 124 128 132 136 140 149 153 157 161 165
May 27 19:14:24 XXX kernel[0] <Notice>: AppleUSBMultitouchDriver::checkStatus - received Status Packet, Payload 2: device was reinitialized
May 27 19:14:24 XXX.local hidd[103] <Notice>: MultitouchHID: device bootloaded
May 27 19:14:24 XXX kernel[0] <Notice>: ARPT: 11253.252277: AirPort_Brcm43xx::powerChange: System Wake - Full Wake/ Dark Wake / Maintenance wake
May 27 19:14:24 XXX.local WindowServer[148] <Error>: disable_update_timeout: UI updates were forcibly disabled by application "Eclipse" for over 1.00 seconds. Server has re-enabled them.
May 27 19:14:24 XXX.local discoveryd[80] <Notice>: Basic DNSResolver UDNSServer:: PowerState is DarkWake
May 27 19:14:24 XXX.local WebEx Productivity Tools[844] <Warning>: NetworkReachability did change
May 27 19:14:24 XXX.local WindowServer[148] <Warning>: common_reenable_update: UI updates were finally reenabled by application "Eclipse" after 4.07 seconds (server forcibly re-enabled them after 4.07 seconds)
May 27 19:14:24 XXX.local UserEventAgent[46] <Notice>: Captive: CNPluginHandler en0: Inactive
May 27 19:14:24 XXX.local configd[57] <Notice>: network changed: v4(en0-:172.29.242.122) DNS- SMB-
May 27 19:14:24 XXX.local discoveryd[80] <Notice>: Basic WABServer NetResolverEvent no resolvers, resetting domains
May 27 19:14:24 XXX.local netbiosd[4991] <Notice>: network_reachability_changed : network is not reachable, netbiosd is shutting down
May 27 19:14:24 XXX.local networkd[161] <Warning>: +[NETLedBelly stopFastFail] Clearing ledbelly failure cache
May 27 19:14:24 XXX kernel[0] <Notice>: en0: channel changed to 1
--- last message repeated 1 time ---
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Failed to enable multicast listener sending for V4 socket en0, 49
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv4 listener multicast send failed en0, 49
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed en0, 49
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Failed to enable multicast listener sending for V4 socket en0, 49
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv4 listener multicast send failed en0, 49
May 27 19:14:24 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Failed to enable multicast listener sending for V4 socket en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv4 listener multicast send failed en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Failed to enable multicast listener sending for V4 socket en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv4 listener multicast send failed en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed en0, 49
May 27 19:14:25 XXX.local discoveryd[80] <Notice>: Basic Bonjour stop listening on en0
May 27 19:14:27 XXX.local discoveryd[80] <Notice>: Basic BTMMServer Sleep offload failed. Start deregistering
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: label: default
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: dbname: od:/Local/Default
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: mkey_file: /var/db/krb5kdc/m-key
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: acl_file: /var/db/krb5kdc/kadmind.acl
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: digest-request: uid=0
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: digest-request: netr probe 1
May 27 19:14:29 XXX.local digest-service[5587] <Notice>: digest-request: init request
May 27 19:14:29 XXX.local com.apple.geod[1035] <Notice>: 2015-05-27 19:14:29.915, 1035, c90f350, [CountryConfiguration]: Could not determine current country code: Error Domain=NSURLErrorDomain Code=-1009 "The Internet connection appears to be offline." UserInfo=0x7fdb0cb13db0 {NSUnderlyingError=0x7fdb0cb11a20 "The Internet connection appears to be offline.", NSErrorFailingURLStringKey=http://gsp1.apple.com/pep/gcc, NSErrorFailingURLKey=http://gsp1.apple.com/pep/gcc, _kCFStreamErrorDomainKey=12, _kCFStreamErrorCodeKey=8, NSLocalizedDescription=The Internet connection appears to be offline.}
May 27 19:14:29 XXX.local sandboxd[176] ([5588]) <Notice>: rpcsvchost(5588) deny file-read-data /private/var/root/Library/Preferences/.GlobalPreferences.plist
May 27 19:14:30 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::setAwdlOperatingMode Setting the AWDL operation mode from SUSPENDED to AUTO
May 27 19:14:30 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::setAwdlAutoMode Resuming AWDL
May 27 19:14:30 XXX kernel[0] <Notice>: PM response took 5455 ms (58, powerd)
May 27 19:14:30 XXX kernel[0] <Notice>: ARPT: 11258.727145: AirPort_Brcm43xx::powerChange: System Sleep
May 27 19:14:30 XXX kernel[0] <Notice>: ARPT: 11258.727150: wl0: powerChange: *** BONJOUR/MDNS OFFLOADS ARE NOT RUNNING.
May 27 19:14:30 XXX kernel[0] <Notice>: hibernate image path: /var/vm/sleepimage
May 27 19:14:30 XXX kernel[0] <Notice>: efi pagecount 63
May 27 19:14:30 XXX kernel[0] <Notice>: hibernate_page_list_setall(preflight 1) start 0xffffff8218ba7000, 0xffffff821b9cd000
May 27 19:14:31 XXX kernel[0] <Notice>: hibernate_page_list_setall time: 592 ms
May 27 19:14:31 XXX kernel[0] <Notice>: pages 3543513, wire 331477, act 1773907, inact 109079, cleaned 0 spec 16, zf 293982, throt 0, compr 0, xpmapped 0
May 27 19:14:31 XXX kernel[0] <Notice>: could discard act 145757 inact 316742 purgeable 27904 spec 544649 cleaned 0
May 27 19:14:31 XXX kernel[0] <Notice>: hibernate_page_list_setall preflight pageCount 331477 est comp 50 setfile 794820608 min 1073741824
May 27 19:14:31 XXX kernel[0] <Notice>: [0x2a7c8e1000, 0x19000000]
May 27 19:14:31 XXX kernel[0] <Notice>: [0x2aac808000, 0x27000000]
May 27 19:14:31 XXX kernel[0] <Notice>: [0x0, 0x0]
May 27 19:14:31 XXX kernel[0] <Notice>: kern_open_file_for_direct_io(0) took 5 ms
May 27 19:14:31 XXX kernel[0] <Notice>: Opened file /var/vm/sleepimage, size 1073741824, partition base 0x0, maxio 2000000 ssd 1
May 27 19:14:31 XXX kernel[0] <Notice>: hibernate image major 1, minor 0, blocksize 4096, pollers 5
May 27 19:15:16 XXX kernel[0] <Notice>: AppleThunderboltNHIType2::waitForOk2Go2Sx - retries = 2
May 28 08:45:10 XXX kernel[0] <Notice>: vm_compressor_record_warmup (1598 - 11364)
May 28 08:45:10 XXX kernel[0] <Notice>: vm_compressor_flush - starting
May 28 08:45:10 XXX kernel[0] <Notice>: pci pause: SDXC
May 28 08:45:10 XXX kernel[0] <Notice>: vm_compressor_flush completed - took 32340 msecs
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_flush_memory: buffer_cache_gc freed up 40597 wired pages
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_alloc_pages act 286221, inact 424546, anon 51493, throt 0, spec 542173, wire 324854, wireinit 282653
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_setup(0) took 45318 ms
May 28 08:45:10 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::doDisable source [handleSIOCSIFFLAGS]
May 28 08:45:10 XXX kernel[0] <Notice>: en0: channel changed to 1
May 28 08:45:10 XXX kernel[0] <Notice>: AirPort: Link Down on awdl0. Reason 1 (Unspecified).
May 28 08:45:10 XXX kernel[0] <Notice>: IO80211AWDLPeerManager::doDisable source [setLinkState]
May 28 08:45:10 XXX kernel[0] <Notice>: Bluetooth -- LE is supported - Disable LE meta event
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_page_list_setall(preflight 0) start 0xffffff8218ba7000, 0xffffff821b9cd000
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_teardown started
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_teardown: wired_pages 332487, free_pages 3786489, active_pages 40040, inactive_pages 0, speculative_pages 2, cleaned_pages 0, compressor_pages 70
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_teardown_pmap_structs done: last_valid_compact_indx 213919
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_teardown: mark_as_unneeded_pages 4096, 57012, 34659
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_teardown completed - discarded 95767
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_page_list_setall time: 2882 ms
May 28 08:45:10 XXX kernel[0] <Notice>: pages 1466275, wire 213232, act 40040, inact 0, cleaned 0 spec 2, zf 0, throt 0, compr 70, xpmapped 40000
May 28 08:45:10 XXX kernel[0] <Notice>: did discard act 218310 inact 424546 purgeable 27904 spec 542171 cleaned 0
May 28 08:45:10 XXX kernel[0] <Notice>: WARNING: hibernate_page_list_setall skipped 71158 xpmapped pages
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_page_list_setall found pageCount 253344
May 28 08:45:10 XXX kernel[0] <Notice>: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
May 28 08:45:10 XXX kernel[0] <Notice>: IOHibernatePollerOpen(0)
May 28 08:45:10 XXX kernel[0] <Notice>: encryptStart 14010
May 28 08:45:10 XXX kernel[0] <Notice>: bitmap_size 0x7f4dc, previewSize 0xcdc640, writing 249710 pages @ 0xd6fb2c
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_rebuild started
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_rebuild_pmap_structs done: last_valid_compact_indx 213919
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_rebuild completed - took 71 msecs
May 28 08:45:10 XXX kernel[0] <Notice>: booter start at 1314 ms smc 0 ms, [14, 0, 0] total 594 ms, dsply 0, 0 ms, tramp 667 ms
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_machine_init: state 2, image pages 213019, sum was 1794ccf8, imageSize 0x1a558000, image1Size 0x114c9000, conflictCount 352, nextFree 1b5
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_newruntime_map time: 0 ms, IOHibernatePollerOpen(), ml_get_interrupts_enabled 0
May 28 08:45:10 XXX kernel[0] <Notice>: IOHibernatePollerOpen(0) 6 ms
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_machine_init reading
May 28 08:45:10 XXX kernel[0] <Notice>: PMStats: Hibernate read took 341 ms
May 28 08:45:10 XXX kernel[0] <Notice>: hibernate_machine_init pagesDone 253131 sum2 457a13ea, time: 341 ms, disk(0x20000) 430 Mb/s, comp bytes: 67006464 time: 61 ms 1034 Mb/s, crypt bytes: 151580672 time: 60 ms 2401 Mb/s
May 28 08:45:10 XXX kernel[0] <Notice>: Wake reason: EC.LidOpen (User)
May 28 08:45:10 XXX kernel[0] <Notice>: vm_compressor_fastwake_warmup (1598 - 11364) - starting
May 28 08:45:10 XXX kernel[0] <Notice>: Previous sleep cause: 5
May 28 08:45:10 XXX kernel[0] <Notice>: Previous shutdown cause: -60
May 28 08:45:10 XXX kernel[0] <Notice>: AppleThunderboltNHIType2::prePCIWake - power up complete - took 0 us
May 28 08:45:10 XXX kernel[0] <Notice>: AppleThunderboltGenericHAL::earlyWake - complete - took 9 milliseconds
May 28 08:45:10 XXX kernel[0] <Notice>: NVRM: 0x702a called in D3
May 28 08:45:10 XXX kernel[0] <Notice>: TBT W (2): 0x0100 [x]
May 28 08:45:10 XXX kernel[0] <Notice>: en0: channel changed to 1
May 28 08:45:10 XXX kernel[0] <Notice>: AirPort: Link Up on awdl0
May 28 08:45:10 XXX kernel[0] <Notice>: IOThunderboltSwitch<0xffffff804be53000>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 11 unplug = 0
May 28 08:45:10 XXX kernel[0] <Notice>: IOThunderboltSwitch<0xffffff804be53000>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 12 unplug = 0
MacBook Pro with Retina display, OS X Yosemite (10.10.3), (Retina, 15-inch, Early 2013)