Background:
-------------------------------------------------------
I've recently been experiencing consistent random sleep failures with my Santa Rosa Macbook Pro. Whether I sleep via the apple menu, or close the lid, the MBP goes through its sleep procedure, then the split second that the MBP latch light begins to breathe, the DVD-drives makes its 'wake up' sound and the MBP powers back on.
Initially I thought my problem was related to an application. Firefox and MSN messenger seemed to consistently cause the problem. Then I found that it can still occur with neither open. It 'seems' that if I turn off my airport immediately prior to sleeping, the MBP will sleep without problems. Sometimes if I turn it off, then immediately turn it back on and sleep, the MBP will sleep okay. This doesn't seem to work consistently.
I had also thought this might have something to do with my airdisk since it's been suffering the (well-documented at this point) problem where airdisks start giving "Unknown user, login [...]" errors. This doesn't seem to be the case though since the problem still occurs if I disable the airdisk agent and don't mount any drives.
NOTE: This doesn't seem to happen with my GF's MBP. It too is a 2.2GHz Santa Rosa machine on the same AEBS network.
System Details:
-------------------------------------------------------
- Santa Rosa 2.2Ghz Stock Macbook Pro
- OSX 10.4.10, all updates installed including airport updates
- Gigabit Airport Extreme with Seagate FreeAgent Pro Airdisk attached via USB
Observations:
-------------------------------------------------------
In my logs below, it seems that a successful sleep does NOT show the following lines in the system log. This might be related to having turned off the airport in order to get a successful sleep:
Sep 19 21:55:27 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:27 dpBook lookupd[1047]: lookupd (version 369.6) starting - Wed Sep 19 21:55:27 2007
Sep 19 21:55:30 dpBook configd[36]: rtmsg: error writing to routing socket
Sep 19 21:55:30 dpBook configd[36]: posting notification com.apple.system.config.network_change
System Log when there is a SUCCESSFUL sleep:
(I had to turn off the airport to get this to work, so that might bias some things)
-------------------------------------------------------
Sep 19 22:06:45 dpBook kernel[0]: AFPSleepWakeHandler: going to sleep
Sep 19 22:06:45 dpBook kernel[0]: hibernate image path: /var/vm/sleepimage
Sep 19 22:06:45 dpBook kernel[0]: sizeof(IOHibernateImageHeader) == 512
Sep 19 22:06:45 dpBook kernel[0]: Opened file /var/vm/sleepimage, size 2147483648, partition base 0xc805000, maxio 400000
Sep 19 22:06:45 dpBook kernel[0]: hibernate image major 14, minor 2, blocksize 512, pollers 3
Sep 19 22:06:45 dpBook kernel[0]: hibernate_alloc_pages flags 00000000, gobbling 0 pages
Sep 19 22:08:22 dpBook kernel[0]: System SafeSleep
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall start
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall time: 126 ms
Sep 19 22:08:22 dpBook kernel[0]: pages 129038, wire 39678, act 8860, inact 15226, zf 6060, could discard act 26460 inact 32754
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall found pageCount 129038
Sep 19 22:08:22 dpBook kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
Sep 19 22:08:22 dpBook kernel[0]: IOHibernatePollerOpen(0)
Sep 19 22:08:22 dpBook kernel[0]: writing 128055 pages
Sep 19 22:08:22 dpBook kernel[0]: image1Size 63602176
Sep 19 22:08:22 dpBook kernel[0]: all time: 2862 ms, comp time: 796 ms, deco time: 0 ms,
Sep 19 22:08:22 dpBook kernel[0]: image 116412928, uncompressed 281972736 (68841), compressed 113939496 (40%), sum1 54c85eb6, sum2 6c204e24
Sep 19 22:08:22 dpBook kernel[0]: hibernate_write_image done(0)
Sep 19 22:08:22 dpBook kernel[0]: sleep
Sep 19 22:08:22 dpBook kernel[0]: Enabling XMM register save/restore and SSE/SSE2 opcodes
Sep 19 22:08:22 dpBook kernel[0]: Started CPU 01
Sep 19 22:08:22 dpBook kernel[0]: IOBluetoothHCIController::restartShutdownWL this is a wake from sleep
Sep 19 22:08:22 dpBook kernel[0]: System Wake
Sep 19 22:08:22 dpBook kernel[0]: IOUSBWorkLoop::closeGate - interrupt Thread being held off
Sep 19 22:08:24 dpBook kernel[0]: AFPSleepWakeHandler: waking up
Sep 19 22:08:24 dpBook BatteryUpdater[1122]: Battery does not need the BatteryUpdate v 1.2 (0x2)
System Log when the MBP sleeps, then immediately wakes up
-------------------------------------------------------
Sep 19 21:55:27 dpBook kernel[0]: AFPSleepWakeHandler: going to sleep
Sep 19 21:55:27 dpBook kernel[0]: hibernate image path: /var/vm/sleepimage
Sep 19 21:55:27 dpBook kernel[0]: sizeof(IOHibernateImageHeader) == 512
Sep 19 21:55:27 dpBook kernel[0]: Opened file /var/vm/sleepimage, size 2147483648, partition base 0xc805000, maxio 400000
Sep 19 21:55:27 dpBook kernel[0]: hibernate image major 14, minor 2, blocksize 512, pollers 3
Sep 19 21:55:27 dpBook kernel[0]: hibernate_alloc_pages flags 00000000, gobbling 0 pages
Sep 19 21:55:27 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:27 dpBook lookupd[1047]: lookupd (version 369.6) starting - Wed Sep 19 21:55:27 2007
Sep 19 21:55:30 dpBook configd[36]: rtmsg: error writing to routing socket
Sep 19 21:55:30 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:37 dpBook kernel[0]: System SafeSleep
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall start
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall time: 117 ms
Sep 19 21:55:37 dpBook kernel[0]: pages 155661, wire 42018, act 10436, inact 30014, zf 14027, could discard act 26319 inact 32847
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall found pageCount 155661
Sep 19 21:55:37 dpBook kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
Sep 19 21:55:37 dpBook kernel[0]: IOHibernatePollerOpen(0)
Sep 19 21:55:37 dpBook kernel[0]: writing 154772 pages
Sep 19 21:55:37 dpBook kernel[0]: image1Size 65746944
Sep 19 21:55:37 dpBook kernel[0]: all time: 3981 ms, comp time: 966 ms, deco time: 0 ms,
Sep 19 21:55:37 dpBook kernel[0]: image 162737152, uncompressed 391602176 (95606), compressed 160500352 (40%), sum1 8225f8f4, sum2 aec6c16a
Sep 19 21:55:37 dpBook kernel[0]: hibernate_write_image done(0)
Sep 19 21:55:37 dpBook kernel[0]: sleep
Sep 19 21:55:37 dpBook kernel[0]: Enabling XMM register save/restore and SSE/SSE2 opcodes
Sep 19 21:55:37 dpBook kernel[0]: Started CPU 01
Sep 19 21:55:37 dpBook kernel[0]: IOBluetoothHCIController::restartShutdownWL this is a wake from sleep
Sep 19 21:55:37 dpBook kernel[0]: System Wake
Sep 19 21:55:37 dpBook kernel[0]: IOUSBWorkLoop::closeGate - interrupt Thread being held off
Sep 19 21:55:39 dpBook kernel[0]: AFPSleepWakeHandler: waking up
Sep 19 21:55:39 dpBook lookupd[1053]: lookupd (version 369.6) starting - Wed Sep 19 21:55:39 2007
Sep 19 21:55:41 dpBook kernel[0]: 11D beacon causing regdomain change to CC 124
Sep 19 21:55:41 dpBook kernel[0]: en1: 802.11d country code set to 'CA'.
Sep 19 21:55:41 dpBook kernel[0]: en1: Supported channels 1 2 3 4 5 6 7 8 9 10 11 36 40 44 48 52 56 60 64 149 153 157 161 165
Sep 19 21:55:43 dpBook BatteryUpdater[1058]: Battery does not need the BatteryUpdate v 1.2 (0x2)
Sep 19 21:55:43 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:43 dpBook lookupd[1060]: lookupd (version 369.6) starting - Wed Sep 19 21:55:43 2007
Sep 19 21:55:44 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:46 dpBook mDNSResponder: Repeated transitions for interface en1 (FE80:0000:0000:0000:021B:63FF:FEC8:9AEA); delaying packets by 5 seconds
Sep 19 21:55:47 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:47 dpBook lookupd[1065]: lookupd (version 369.6) starting - Wed Sep 19 21:55:47 2007
Sep 19 21:55:49 dpBook mDNSResponder: Repeated transitions for interface en1 ( 10.0.1.197); delaying packets by 5 seconds
-------------------------------------------------------
I've recently been experiencing consistent random sleep failures with my Santa Rosa Macbook Pro. Whether I sleep via the apple menu, or close the lid, the MBP goes through its sleep procedure, then the split second that the MBP latch light begins to breathe, the DVD-drives makes its 'wake up' sound and the MBP powers back on.
Initially I thought my problem was related to an application. Firefox and MSN messenger seemed to consistently cause the problem. Then I found that it can still occur with neither open. It 'seems' that if I turn off my airport immediately prior to sleeping, the MBP will sleep without problems. Sometimes if I turn it off, then immediately turn it back on and sleep, the MBP will sleep okay. This doesn't seem to work consistently.
I had also thought this might have something to do with my airdisk since it's been suffering the (well-documented at this point) problem where airdisks start giving "Unknown user, login [...]" errors. This doesn't seem to be the case though since the problem still occurs if I disable the airdisk agent and don't mount any drives.
NOTE: This doesn't seem to happen with my GF's MBP. It too is a 2.2GHz Santa Rosa machine on the same AEBS network.
System Details:
-------------------------------------------------------
- Santa Rosa 2.2Ghz Stock Macbook Pro
- OSX 10.4.10, all updates installed including airport updates
- Gigabit Airport Extreme with Seagate FreeAgent Pro Airdisk attached via USB
Observations:
-------------------------------------------------------
In my logs below, it seems that a successful sleep does NOT show the following lines in the system log. This might be related to having turned off the airport in order to get a successful sleep:
Sep 19 21:55:27 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:27 dpBook lookupd[1047]: lookupd (version 369.6) starting - Wed Sep 19 21:55:27 2007
Sep 19 21:55:30 dpBook configd[36]: rtmsg: error writing to routing socket
Sep 19 21:55:30 dpBook configd[36]: posting notification com.apple.system.config.network_change
System Log when there is a SUCCESSFUL sleep:
(I had to turn off the airport to get this to work, so that might bias some things)
-------------------------------------------------------
Sep 19 22:06:45 dpBook kernel[0]: AFPSleepWakeHandler: going to sleep
Sep 19 22:06:45 dpBook kernel[0]: hibernate image path: /var/vm/sleepimage
Sep 19 22:06:45 dpBook kernel[0]: sizeof(IOHibernateImageHeader) == 512
Sep 19 22:06:45 dpBook kernel[0]: Opened file /var/vm/sleepimage, size 2147483648, partition base 0xc805000, maxio 400000
Sep 19 22:06:45 dpBook kernel[0]: hibernate image major 14, minor 2, blocksize 512, pollers 3
Sep 19 22:06:45 dpBook kernel[0]: hibernate_alloc_pages flags 00000000, gobbling 0 pages
Sep 19 22:08:22 dpBook kernel[0]: System SafeSleep
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall start
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall time: 126 ms
Sep 19 22:08:22 dpBook kernel[0]: pages 129038, wire 39678, act 8860, inact 15226, zf 6060, could discard act 26460 inact 32754
Sep 19 22:08:22 dpBook kernel[0]: hibernate_page_list_setall found pageCount 129038
Sep 19 22:08:22 dpBook kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
Sep 19 22:08:22 dpBook kernel[0]: IOHibernatePollerOpen(0)
Sep 19 22:08:22 dpBook kernel[0]: writing 128055 pages
Sep 19 22:08:22 dpBook kernel[0]: image1Size 63602176
Sep 19 22:08:22 dpBook kernel[0]: all time: 2862 ms, comp time: 796 ms, deco time: 0 ms,
Sep 19 22:08:22 dpBook kernel[0]: image 116412928, uncompressed 281972736 (68841), compressed 113939496 (40%), sum1 54c85eb6, sum2 6c204e24
Sep 19 22:08:22 dpBook kernel[0]: hibernate_write_image done(0)
Sep 19 22:08:22 dpBook kernel[0]: sleep
Sep 19 22:08:22 dpBook kernel[0]: Enabling XMM register save/restore and SSE/SSE2 opcodes
Sep 19 22:08:22 dpBook kernel[0]: Started CPU 01
Sep 19 22:08:22 dpBook kernel[0]: IOBluetoothHCIController::restartShutdownWL this is a wake from sleep
Sep 19 22:08:22 dpBook kernel[0]: System Wake
Sep 19 22:08:22 dpBook kernel[0]: IOUSBWorkLoop::closeGate - interrupt Thread being held off
Sep 19 22:08:24 dpBook kernel[0]: AFPSleepWakeHandler: waking up
Sep 19 22:08:24 dpBook BatteryUpdater[1122]: Battery does not need the BatteryUpdate v 1.2 (0x2)
System Log when the MBP sleeps, then immediately wakes up
-------------------------------------------------------
Sep 19 21:55:27 dpBook kernel[0]: AFPSleepWakeHandler: going to sleep
Sep 19 21:55:27 dpBook kernel[0]: hibernate image path: /var/vm/sleepimage
Sep 19 21:55:27 dpBook kernel[0]: sizeof(IOHibernateImageHeader) == 512
Sep 19 21:55:27 dpBook kernel[0]: Opened file /var/vm/sleepimage, size 2147483648, partition base 0xc805000, maxio 400000
Sep 19 21:55:27 dpBook kernel[0]: hibernate image major 14, minor 2, blocksize 512, pollers 3
Sep 19 21:55:27 dpBook kernel[0]: hibernate_alloc_pages flags 00000000, gobbling 0 pages
Sep 19 21:55:27 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:27 dpBook lookupd[1047]: lookupd (version 369.6) starting - Wed Sep 19 21:55:27 2007
Sep 19 21:55:30 dpBook configd[36]: rtmsg: error writing to routing socket
Sep 19 21:55:30 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:37 dpBook kernel[0]: System SafeSleep
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall start
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall time: 117 ms
Sep 19 21:55:37 dpBook kernel[0]: pages 155661, wire 42018, act 10436, inact 30014, zf 14027, could discard act 26319 inact 32847
Sep 19 21:55:37 dpBook kernel[0]: hibernate_page_list_setall found pageCount 155661
Sep 19 21:55:37 dpBook kernel[0]: IOHibernatePollerOpen, ml_get_interrupts_enabled 0
Sep 19 21:55:37 dpBook kernel[0]: IOHibernatePollerOpen(0)
Sep 19 21:55:37 dpBook kernel[0]: writing 154772 pages
Sep 19 21:55:37 dpBook kernel[0]: image1Size 65746944
Sep 19 21:55:37 dpBook kernel[0]: all time: 3981 ms, comp time: 966 ms, deco time: 0 ms,
Sep 19 21:55:37 dpBook kernel[0]: image 162737152, uncompressed 391602176 (95606), compressed 160500352 (40%), sum1 8225f8f4, sum2 aec6c16a
Sep 19 21:55:37 dpBook kernel[0]: hibernate_write_image done(0)
Sep 19 21:55:37 dpBook kernel[0]: sleep
Sep 19 21:55:37 dpBook kernel[0]: Enabling XMM register save/restore and SSE/SSE2 opcodes
Sep 19 21:55:37 dpBook kernel[0]: Started CPU 01
Sep 19 21:55:37 dpBook kernel[0]: IOBluetoothHCIController::restartShutdownWL this is a wake from sleep
Sep 19 21:55:37 dpBook kernel[0]: System Wake
Sep 19 21:55:37 dpBook kernel[0]: IOUSBWorkLoop::closeGate - interrupt Thread being held off
Sep 19 21:55:39 dpBook kernel[0]: AFPSleepWakeHandler: waking up
Sep 19 21:55:39 dpBook lookupd[1053]: lookupd (version 369.6) starting - Wed Sep 19 21:55:39 2007
Sep 19 21:55:41 dpBook kernel[0]: 11D beacon causing regdomain change to CC 124
Sep 19 21:55:41 dpBook kernel[0]: en1: 802.11d country code set to 'CA'.
Sep 19 21:55:41 dpBook kernel[0]: en1: Supported channels 1 2 3 4 5 6 7 8 9 10 11 36 40 44 48 52 56 60 64 149 153 157 161 165
Sep 19 21:55:43 dpBook BatteryUpdater[1058]: Battery does not need the BatteryUpdate v 1.2 (0x2)
Sep 19 21:55:43 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:43 dpBook lookupd[1060]: lookupd (version 369.6) starting - Wed Sep 19 21:55:43 2007
Sep 19 21:55:44 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:46 dpBook mDNSResponder: Repeated transitions for interface en1 (FE80:0000:0000:0000:021B:63FF:FEC8:9AEA); delaying packets by 5 seconds
Sep 19 21:55:47 dpBook configd[36]: posting notification com.apple.system.config.network_change
Sep 19 21:55:47 dpBook lookupd[1065]: lookupd (version 369.6) starting - Wed Sep 19 21:55:47 2007
Sep 19 21:55:49 dpBook mDNSResponder: Repeated transitions for interface en1 ( 10.0.1.197); delaying packets by 5 seconds