Yup hi fellow uk-r! 👋Also - as another thought - are we all UK users with this problem? (I am.)
❯ log show --start '2022-01-28' | rg -i "Wake reason" -C10
(with ripgrep)2022-01-28 02:59:03.134351+0100 0x337 Default 0x0 0 0 kernel: (IOBluetoothHIDDriver) **** [IOBluetoothHIDDriver][AppleHSBluetoothDevice][handleSleep][c0-a5-3e-08-7e-cd] -- setting mHandshakeTimeoutCounter to 1 and _mUseSleepTimeout to true -- this = 0x3D80 ****
2022-01-28 02:59:03.134390+0100 0x337 Default 0x0 149 0 bluetoothd: [com.apple.bluetooth:Server.HID] HIDProfile::prepareForSleep() -- Sending setReport via enqueueUserSpaceHIDDataForDevice for Clear Wake Reason for device 181CFC5C
2022-01-28 02:59:04.865654+0100 0x10d538 Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] [Error] AppleDeviceManagementHIDEventService::setWakeReason Error setting wake reason (reportID = 0xc5, Return Value = 0xe00002ca)
--
2022-01-28 02:59:05.419070+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->XFirmwareWakingVector: 0x0
2022-01-28 02:59:05.419073+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->OspmFlags: 0x0
2022-01-28 02:59:05.430558+0100 0x74 Default 0x0 0 0 kernel: [105506.902204]: IOPlatformQuiesceAction -> AppleVTD
2022-01-28 02:59:05.430562+0100 0x74 Default 0x0 0 0 kernel: [105506.903624]: IOPlatformActiveAction -> AppleVTD
2022-01-28 02:59:05.430568+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) ACPI S3 WAKE
2022-01-28 02:59:05.441188+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->FirmwareWakingVector: 0x0
2022-01-28 02:59:05.441192+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->Length: 0x40
2022-01-28 02:59:05.441195+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->Version: 0x1
2022-01-28 02:59:05.441198+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->XFirmwareWakingVector: 0x0
2022-01-28 02:59:05.441201+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) Facs->OspmFlags: 0x0
2022-01-28 02:59:05.446758+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) AppleACPIPlatformPower Wake reason: EC.DarkPME (Maintenance)
2022-01-28 02:59:05.446762+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) AppleACPIPlatformPower Wake reason: EC.DarkPME (Maintenance)
2022-01-28 02:59:05.446777+0100 0x74 Default 0x0 0 0 kernel: PMRD: trace point 0x22
2022-01-28 02:59:05.446954+0100 0x74 Default 0x0 0 0 kernel: IOPlatformWakeAction -> AppleThunderboltHAL
2022-01-28 02:59:05.446963+0100 0x74 Default 0x0 0 0 kernel: IOPlatformWakeAction -> AppleThunderboltHAL
2022-01-28 02:59:05.446969+0100 0x74 Default 0x0 0 0 kernel: IOPlatformWakeAction -> AppleRTC
2022-01-28 02:59:05.446976+0100 0x74 Default 0x0 0 0 kernel: IOPlatformWakeAction -> AppleKeyStore
2022-01-28 02:59:05.446983+0100 0x74 Default 0x0 0 0 kernel: IOPlatformWakeAction -> AppleSMC
2022-01-28 02:59:05.446992+0100 0x74 Default 0x0 0 0 kernel: PMRD: trace point 0x23
2022-01-28 02:59:05.451165+0100 0x74 Default 0x0 0 0 kernel: Started cpu 2 (lapic id 00000002)
2022-01-28 02:59:05.455256+0100 0x74 Default 0x0 0 0 kernel: Started cpu 4 (lapic id 00000004)
2022-01-28 02:59:05.459353+0100 0x74 Default 0x0 0 0 kernel: Started cpu 6 (lapic id 00000006)
--
2022-01-28 02:59:10.596056+0100 0x74 Default 0x0 0 0 kernel: PMRD: PowerChangeDone: ON_STATE->ON_STATE
2022-01-28 02:59:10.596060+0100 0x74 Default 0x0 0 0 kernel: PMRD: PowerChangeOverride (ON_STATE->ON_STATE, 9, 0x2) tag 0x1ae80008
2022-01-28 02:59:10.596061+0100 0x74 Default 0x0 0 0 kernel: PMRD: PowerChangeDone: ON_STATE->ON_STATE
2022-01-28 02:59:10.596066+0100 0x74 Default 0x0 0 0 kernel: PMRD: evaluatePolicy( 6, 0xd35 )
2022-01-28 02:59:10.596067+0100 0x74 Default 0x0 0 0 kernel: PMRD: dark wake entry
2022-01-28 02:59:10.596070+0100 0x74 Default 0x0 0 0 kernel: PMRD: DarkWake: sleepASAP 1, clamshell closed 1, disabled 0/1, desktopMode 0, ac 0
2022-01-28 02:59:10.596072+0100 0x74 Default 0x0 0 0 kernel: PMRD: System sleep prevented by kPMCPUAssertion
2022-01-28 02:59:10.596091+0100 0x10d53c Activity 0x158524 136 0 dasd: (CoreDuetContext) Duet: ClientContext objectForContextualKeyPath:
2022-01-28 02:59:10.596384+0100 0x10d65c Default 0x0 0 0 kernel: PMRD: disk spindown accelerated, was 10 min
2022-01-28 02:59:10.596749+0100 0x10d53c Activity 0x158525 136 0 dasd: (CoreDuetContext) Duet: ClientContext objectForContextualKeyPath:
2022-01-28 02:59:10.599659+0100 0x10d642 Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] AppleDeviceManagementHIDEventService::processWakeReason Wake reason: Host (0x01)
2022-01-28 02:59:10.601493+0100 0x326 Default 0x0 121 0 apsd: <private>: prepareForDarkWake
2022-01-28 02:59:10.601526+0100 0x4e3 Default 0x0 221 0 mDNSResponder: [com.apple.mDNSResponder:Default] <private>
2022-01-28 02:59:10.601527+0100 0x326 Default 0x0 121 0 apsd: [com.apple.apsd:daemon] <private>: prepareForDarkWake
2022-01-28 02:59:10.601527+0100 0x4e3 Default 0x0 221 0 mDNSResponder: [com.apple.mDNSResponder:Default] <private>
2022-01-28 02:59:10.601529+0100 0x4e3 Default 0x0 221 0 mDNSResponder: [com.apple.mDNSResponder:Default] <private>
2022-01-28 02:59:10.601734+0100 0x10d587 Activity 0x1585bb 184 0 runningboardd: (RunningBoard) acquireAssertionWithDescriptor
2022-01-28 02:59:10.601832+0100 0x10d587 Default 0x1585bb 184 0 runningboardd: (RunningBoard) [com.apple.runningboard:assertion] Acquiring assertion targeting [daemon<com.apple.apsd>:121] from originator [daemon<com.apple.powerd>:85] with description <RBSAssertionDescriptor| "App is holding power assertion" ID:184-85-36198 target:121 attributes:[
<RBSDomainAttribute| domain:"com.apple.appnap" name:"PowerAssertion" sourceEnvironment:"(null)">,
<RBSAcquisitionCompletionAttribute| policy:AfterApplication>
]>
--
2022-01-28 02:59:10.599659+0100 0x10d642 Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] AppleDeviceManagementHIDEventService::processWakeReason Wake reason: Host (0x01)
2022-01-28 02:59:10.601493+0100 0x326 Default 0x0 121 0 apsd: <private>: prepareForDarkWake
2022-01-28 02:59:11.050496+0100 0x10d66c Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] AppleDeviceManagementHIDEventService::processWakeReason Wake reason: Host (0x01)
2022-01-28 02:59:41.172745+0100 0x337 Default 0x0 149 0 bluetoothd: [com.apple.bluetooth:Server.HID] HIDProfile::prepareForSleep() -- Sending setReport via enqueueUserSpaceHIDDataForDevice for Clear Wake Reason for device 181CFC5C
2022-01-28 02:59:44.391890+0100 0x10d66c Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] [Error] AppleDeviceManagementHIDEventService::setWakeReason Error setting wake reason (reportID = 0xc5, Return Value = 0xe00002ca)
2022-01-28 02:59:45.786516+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) AppleACPIPlatformPower Wake reason: EC.DarkPME (Maintenance)
2022-01-28 02:59:45.786518+0100 0x74 Default 0x0 0 0 kernel: (AppleACPIPlatform) AppleACPIPlatformPower Wake reason: EC.DarkPME (Maintenance)
2022-01-28 03:03:41.706708+0100 0x76 Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] AppleDeviceManagementHIDEventService::processWakeReason Wake reason: Host (0x01)
2022-01-28 03:04:15.060133+0100 0x94b Default 0x0 0 0 kernel: (AppleTopCaseHIDEventDriver) [HID] [ATC] AppleDeviceManagementHIDEventService::processWakeReason Wake reason: Keyboard (0x02)
❯ log show --start '2022-01-28' | rg -i "Wake Request" -C10
2022-01-28 02:58:27.175901+0100 0x10d192 Default 0x0 85 0 powerd: [powerd:wakeRequests] Selected RTC wake request: {
UserVisible = 0;
appPID = 1292;
eventtype = wake;
scheduledby = "com.apple.alarm.user-visible-Weekly Usage Report";
time = "2022-01-31 02:04:38 +0000";
}
2022-01-28 02:58:44.964103+0100 0x10d3e1 Default 0x0 85 0 powerd: [powerd:wakeRequests] Selected RTC wake request: {
UserVisible = 0;
appPID = 1292;
eventtype = wake;
scheduledby = "com.apple.alarm.user-visible-Weekly Usage Report";
time = "2022-01-31 02:04:38 +0000";
}
2022-01-28 02:59:04.606456+0100 0x10d4ef Default 0x0 85 0 powerd: [powerd:wakeRequests] Selected RTC wake request: {
UserVisible = 0;
appPID = 1292;
eventtype = wake;
scheduledby = "com.apple.alarm.user-visible-Weekly Usage Report";
time = "2022-01-31 02:04:38 +0000";
}
❯ pmset -g log |grep "Wake Request"
2022-01-28 02:58:27 +0100 Wake Requests [*process=powerd request=TCPKATurnOff deltaSecs=20540 wakeAt=2022-01-28 08:40:47] [process=powerd request=UserWake deltaSecs=259571 wakeAt=2022-01-31 03:04:38 info="com.apple.alarm.user-visible-Weekly Usage Report,1292"]
2022-01-28 02:58:44 +0100 Wake Requests [*process=powerd request=TCPKATurnOff deltaSecs=20522 wakeAt=2022-01-28 08:40:47] [process=powerd request=UserWake deltaSecs=259553 wakeAt=2022-01-31 03:04:38 info="com.apple.alarm.user-visible-Weekly Usage Report,1292"]
2022-01-28 02:59:04 +0100 Wake Requests [*process=powerd request=TCPKATurnOff deltaSecs=20503 wakeAt=2022-01-28 08:40:47] [process=powerd request=UserWake deltaSecs=259533 wakeAt=2022-01-31 03:04:38 info="com.apple.alarm.user-visible-Weekly Usage Report,1292"]
2022-01-28 02:59:42 +0100 Wake Requests [*process=powerd request=TCPKATurnOff deltaSecs=20465 wakeAt=2022-01-28 08:40:47] [process=powerd request=UserWake deltaSecs=259495 wakeAt=2022-01-31 03:04:38 info="com.apple.alarm.user-visible-Weekly Usage Report,1292"]
2022-01-28 02:59:55 +0100 Wake Requests [*process=powerd request=UserWake deltaSecs=259483 wakeAt=2022-01-31 03:04:38 info="com.apple.alarm.user-visible-Weekly Usage Report,1292"]
❯ pmset -g log | grep -i "wake from"
2022-01-28 02:45:30 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:16%) 17 secs
2022-01-28 02:45:55 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:13%) 10 secs
2022-01-28 02:46:13 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:16%) 45 secs
2022-01-28 02:47:27 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:15%) 21 secs
2022-01-28 02:48:08 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:12%) 17 secs
2022-01-28 02:48:50 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:12%) 11 secs
2022-01-28 02:49:19 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:12%) 25 secs
2022-01-28 02:50:30 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:12%) 37 secs
2022-01-28 02:51:15 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:12%) 14 secs
2022-01-28 02:51:58 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:11%) 37 secs
2022-01-28 02:53:24 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:11%) 45 secs
2022-01-28 02:54:48 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:11%) 11 secs
2022-01-28 02:55:07 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:11%) 9 secs
2022-01-28 02:55:24 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 11 secs
2022-01-28 02:55:56 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 20 secs
2022-01-28 02:56:44 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 10 secs
2022-01-28 02:57:44 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 41 secs
2022-01-28 02:58:33 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 9 secs
2022-01-28 02:58:51 +0100 DarkWake DarkWake from Normal Sleep [CDN] : due to EC.DarkPME/Maintenance Using BATT (Charge:10%) 11 secs
Thank you; I forgot also. Nice to know the bug is (soon) mended.oops: was posting this as fixed in 12.3 beta 3 (here), but... already reported that!