search

homebridge / HAP-NodeJS

Node.js implementation of the HomeKit Accessory Protocol (HAP)
Apache License 2.0
2.69k stars 630 forks source link

After PI reboot accessory can't connect #171

Closed dodgekaze closed 8 years ago

dodgekaze commented 8 years ago

When rebooting the PI, I have to add my two lamps again to my homekit. I have to delete the products and then add them again. This is very annoying.

I hope there will be a solution, if you want more information please ask!

Thank you.

WoodySlum commented 8 years ago

Same as #161

KhaosT commented 8 years ago

This is so strange... Can you post logs from HomeKit on iOS by opening Xcode -> Window -> Devices screen shot 2015-11-29 at 2 03 38 am Close hap-nodejs, wait ~10s then restart hap-nodejs. there should be logs from "homed"

WoodySlum commented 8 years ago

Sorry for the late answer, so it seem that on my side, the Apple TV is the problem. Here is the logs from HAP (192.168.0.39 is my Apple's TV) :

*** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
*** WARNING *** Please fix your application to use the native API of Avahi!
*** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node>
*** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
*** WARNING *** Please fix your application to use the native API of Avahi!
*** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister>
Wed, 09 Dec 2015 17:50:56 GMT Accessory [Hautomation] Creating new AccessoryInfo for our HAP server
Wed, 09 Dec 2015 17:50:56 GMT Accessory [Hautomation] Creating new IdentifierCache
Wed, 09 Dec 2015 17:50:56 GMT EventedHTTPServer Server listening on port 51826

Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 17:59:54 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 17:59:54 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 17:59:54 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 17:59:56 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 17:59:56 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 17:59:56 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 17:59:58 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 17:59:58 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 17:59:58 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 18:00:00 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 18:00:00 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 18:00:00 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed

Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 18:02:28 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 18:02:28 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 18:02:28 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] New connection from client
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] HTTP server listening on port 54826
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] HTTP request: /pair-verify
Wed, 09 Dec 2015 18:02:30 GMT HAPServer [7F:95:F9:BE:0E:6C] HAP Request: POST /pair-verify
Wed, 09 Dec 2015 18:02:30 GMT HAPServer [7F:95:F9:BE:0E:6C] Pair verify step 1/2
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] HTTP Repsonse is finished
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] Client connection closed
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] HTTP connection was closed
Wed, 09 Dec 2015 18:02:30 GMT EventedHTTPServer [192.168.0.39] HTTP server was closed

I also extract the logs from Xcode on the apple tv :


Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.357611 wlan0.A[241841] AppleBCMWLANCore::parseBdcTags(): AppleBCMWLANCore::parseBdcTags <0x80194f80> len 8 Data :325306.357667 wlan0.A[241842] wlan  (22)
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0000: 12 06 00 fa f6 f2 67 13 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 00  | ......g.ps.......W....
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.357723 wlan0.N[241843] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.357753 wlan0.A[241844] AppleBCMWLANCore::handleDataPacket():  Wake up packet received: Data
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.357948 wlan0.A[241845] wlan Wakeup Packet: (220)
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0000: 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 00 45 00 00 ce 03 ac 40 00 2a 06 d7 8c 11 8f a2 93 c0 a8  | ps.......W....E.....@.*.........
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0032: 00 27 14 67 cf 50 d6 82 3c f6 75 5c 3e 24 80 18 00 b6 d8 27 00 00 01 01 08 0a 75 19 ca 88 16 b9  | .'.g.P..<.u\>$.....'......u.....
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0064: dc 1b 17 03 01 00 20 a2 72 7c 8c a4 6b eb 05 4b 79 16 46 a8 50 44 4d 5b 2e a1 a8 3a ac 5c 25 aa  | ...... .r|..k..Ky.F.PDM[...:.\%.
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0096: cb 9a 92 67 0d 89 7c 17 03 01 00 70 95 da 51 3a 38 f9 b7 71 ac b0 d3 31 82 ed b7 b8 55 c8 3f c5  | ...g..|....p..Q:8..q...1....U.?.
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0128: 47 4a da 55 3a 15 c0 35 93 c3 64 42 64 9b 53 db 64 29 71 49 35 27 f3 88 9c 5b 6c 79 8f 5f bc 6a  | GJ.U:..5..dBd.S.d)qI5'...[ly._.j
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0160: 86 c5 0d bf 5d 94 20 94 86 ef dc 1d 7d 68 bd 81 09 44 5a ab be 1e 83 e3 71 8f b9 22 93 15 64 3c  | ....]. .....}h...DZ.....q.."..d<
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: wlan 0192: 37 c6 22 c4 7a a5 f0 08 58 76 63 cc ab 3f a6 06 f9 e5 af e6 f8 13 fa a6 e8 8a 99 a2  | 7.".z...Xvc..?..............
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.397562 wlan0.N[241846] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.397619 wlan0.A[241847] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 273319ms SleepDuration 278808ms DutyCycle 1.9% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb293f300 has been configured
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.429171 wlan0.A[241848] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.445117 wlan0.A[241849] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 09:49:31 AppleTV discoveryd[54] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed awdl0, 49
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: 325306.781235 wlan0.A[241850] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: system wake events:wlan
Dec  9 09:49:31 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 09:49:31 AppleTV powerd[43] <Notice>: 
Dec  9 09:49:31 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 09:49:31 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 09:49:31 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb293f300 has been started
Dec  9 09:49:31 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 09:49:31 AppleTV CLTM[18] <Notice>: CLTM: resetting temps: now = 1449683371, last update = 1449683090
Dec  9 09:49:48 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 09:49:48 AppleTV backboardd[44] <Notice>: 2015-12-09 09:49:48.266447 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 09:49:48 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 09:49:48 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.564402 wlan0.N[241851] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.564456 wlan0.N[241852] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.564490 wlan0.N[241853] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.597306 wlan0.A[241854] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.597358 wlan0.N[241855] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 09:49:48 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.693018 wlan0.A[241856] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.693093 wlan0.A[241857] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.693129 wlan0.A[241858] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325323.868978 wlan0.N[241859] (ID=107) 
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325324.077078 wlan0.N[241860] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 512 ms
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: 325324.077552 wlan0.N[241861] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 513 ms
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 09:49:48 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 104 ms
Dec  9 09:49:49 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 503 ms
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(1) to(2) async took 570 ms
Dec  9 09:49:49 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: 325325.626386 wlan0.N[241862] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: 325325.626431 wlan0.N[241863] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 09:54:43 AppleTV kernel[0] <Notice>: 325325.626462 wlan0.N[241864] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 09:54:43 AppleTV backboardd[44] <Notice>: 2015-12-09 09:54:43.879926 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 09:54:43 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 09:54:43 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 09:54:43 AppleTV powerd[43] <Notice>: 
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 107 ms
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.908499 wlan0.N[241865] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.908562 wlan0.A[241866] AppleBCMWLANCore::parseBdcTags(): AppleBCMWLANCore::parseBdcTags <0x80194f80> len 8 Data :325325.908619 wlan0.A[241867] wlan  (22)
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0000: 12 06 00 92 be b1 6c 13 70 73 cb d8 ff fc 00 24 d4 af 87 5a 08 00  | ......l.ps.....$...Z..
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.908674 wlan0.N[241868] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.908706 wlan0.A[241869] AppleBCMWLANCore::handleDataPacket():  Wake up packet received: Data
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.908902 wlan0.A[241870] wlan Wakeup Packet: (220)
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0000: 70 73 cb d8 ff fc 00 24 d4 af 87 5a 08 00 45 00 00 ce 03 b1 40 00 2b 06 d6 87 11 8f a2 93 c0 a8  | ps.....$...Z..E.....@.+.........
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0032: 00 27 14 67 cf 50 d6 82 3d 90 75 5c 3e ae 80 18 00 c3 4f 7f 00 00 01 01 08 0a 75 1e 9f b9 16 ba  | .'.g.P..=.u\>.....O.......u.....
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0064: 2e 41 17 03 01 00 20 ba dc d5 34 f4 83 00 3d 00 a0 5e 0d ef 4f dd cf 7e 1b f2 a4 37 ad 28 6b 10  | .A.... ...4...=..^..O..~...7.(k.
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0096: d3 88 af 12 01 ae 8f 17 03 01 00 70 c0 eb b7 75 e2 d0 84 e2 47 33 21 19 99 26 a3 7d ba 51 ad b8  | ...........p...u....G3!..&.}.Q..
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0128: 9c ce ac 1e 0e 1c 0a be d9 22 e4 90 ed a2 81 4c 1e 0a 06 4a 4a b7 18 47 a8 76 13 e8 b2 24 0b 54  | .........".....L...JJ..G.v...$.T
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0160: e5 bc ed 2d 4b c7 85 0b fe 9f ea 8c f2 54 d4 bf 0e ed 1e ee 1d c9 a7 a9 bf 1d 60 0e 8d 1c a3 1a  | ...-K........T............`.....
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: wlan 0192: 55 85 6e 53 85 54 8c ae ad 3f 83 50 32 67 d5 91 c1 9d 01 4c b9 52 4d 51 c4 23 34 6c  | U.nS.T...?.P2g.....L.RMQ.#4l
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.948361 wlan0.N[241871] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.948418 wlan0.A[241872] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 290032ms SleepDuration 295800ms DutyCycle 1.9% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa99d1a80 has been configured
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.980272 wlan0.A[241873] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325325.996233 wlan0.A[241874] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 09:54:44 AppleTV discoveryd[54] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed awdl0, 49
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: 325326.332361 wlan0.A[241875] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: system wake events:wlan
Dec  9 09:54:44 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 09:54:44 AppleTV powerd[43] <Notice>: 
Dec  9 09:54:44 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 09:54:44 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 09:54:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa99d1a80 has been started
Dec  9 09:54:44 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 09:54:45 AppleTV CLTM[18] <Notice>: CLTM: resetting temps: now = 1449683685, last update = 1449683386
Dec  9 09:55:12 AppleTV syncdefaultsd[5225] <Notice>: (Note ) marked "com.me.keyvalueservice" topic as "opportunistic" on <APSConnection: 0x1658e340>
Dec  9 09:56:01 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 09:56:01 AppleTV backboardd[44] <Notice>: 2015-12-09 09:56:01.960390 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 09:56:01 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 100 ms (54, discoveryd)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 105 ms (22, sharingd)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 106 ms (43, powerd)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 105 ms (43, powerd)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 106 ms (63, CommCenterClassi)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 106 ms (50, awdd)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: PM response took 306 ms (66, coreduetd)
Dec  9 09:56:02 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.044699 wlan0.N[241876] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.044752 wlan0.N[241877] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.044783 wlan0.N[241878] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.076416 wlan0.A[241879] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.076466 wlan0.N[241880] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 09:56:02 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.172128 wlan0.A[241881] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.172203 wlan0.A[241882] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.172239 wlan0.A[241883] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.348084 wlan0.N[241884] (ID=107) 
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.556200 wlan0.N[241885] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 511 ms
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: 325404.556655 wlan0.N[241886] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 512 ms
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 09:56:02 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 104 ms
Dec  9 09:56:03 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 503 ms
Dec  9 09:56:03 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 09:59:43 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 09:59:43 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 09:59:43 AppleTV kernel[0] <Notice>: 325405.534926 wlan0.N[241887] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 09:59:43 AppleTV kernel[0] <Notice>: 325405.534971 wlan0.N[241888] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 09:59:43 AppleTV kernel[0] <Notice>: 325405.535002 wlan0.N[241889] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 09:59:43 AppleTV backboardd[44] <Notice>: 2015-12-09 09:59:43.879935 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 09:59:43 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 09:59:43 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 09:59:43 AppleTV powerd[43] <Notice>: 
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 107 ms
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.817125 wlan0.N[241890] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.817190 wlan0.A[241891] AppleBCMWLANCore::parseBdcTags(): AppleBCMWLANCore::parseBdcTags <0x80194f80> len 8 Data :325405.817247 wlan0.A[241892] wlan  (22)
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0000: 12 06 00 9a 8c 3d 71 13 70 73 cb d8 ff fc 00 24 d4 af 87 5a 08 00  | .....=q.ps.....$...Z..
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.817302 wlan0.N[241893] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.817334 wlan0.A[241894] AppleBCMWLANCore::handleDataPacket():  Wake up packet received: Data
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.817529 wlan0.A[241895] wlan Wakeup Packet: (220)
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0000: 70 73 cb d8 ff fc 00 24 d4 af 87 5a 08 00 45 00 00 ce 03 ba 40 00 2b 06 d6 7e 11 8f a2 93 c0 a8  | ps.....$...Z..E.....@.+..~......
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0032: 00 27 14 67 cf 50 d6 82 3f 0e 75 5c 40 2c 80 18 00 db ba ac 00 00 01 01 08 0a 75 23 2b a5 16 bb  | .'.g.P..?.u\@,............u#+...
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0064: 61 5e 17 03 01 00 20 af 3c 75 70 a6 df ad 7f 17 d5 48 2c f2 32 94 d3 45 3d 6b 43 35 25 ff 81 1c  | a^.... .<up......H,.2..E=kC5%...
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0096: a8 be b1 ac 41 83 01 17 03 01 00 70 d1 08 33 01 1b 23 44 5d ef 0d 8c c2 04 73 ab 31 24 b2 5d 14  | ....A......p..3..#D].....s.1$.].
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0128: 55 d9 33 ee 5f 91 34 2e 79 4f ec ae 6e 03 dd 9a 7c e1 3e 1c 6c f1 00 7d 37 69 44 85 46 05 bf dd  | U.3._.4.yO..n...|.>.l..}7iD.F...
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0160: 2e f3 94 d6 a4 20 54 e1 4c bb 4c 92 68 c8 d6 3f 3d 2e 29 40 28 59 26 85 98 45 f0 ed 9d 3d 62 92  | ..... T.L.L.h..?=.)@(Y&..E...=b.
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: wlan 0192: f2 f3 fa ed ab 7f 52 b2 5e 9b d5 1d 77 8d 50 ac 04 3e bc db 7a 5c 54 43 2c cb 66 ee  | ......R.^...w.P..>..z\TC,.f.
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.856940 wlan0.N[241896] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.856996 wlan0.A[241897] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 217499ms SleepDuration 221872ms DutyCycle 1.9% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa9981c00 has been configured
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.888851 wlan0.A[241898] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325405.904995 wlan0.A[241899] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: 325406.240889 wlan0.A[241900] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: system wake events:wlan
Dec  9 09:59:44 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 09:59:44 AppleTV powerd[43] <Notice>: 
Dec  9 09:59:44 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 09:59:44 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 09:59:44 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa9981c00 has been started
Dec  9 09:59:44 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 09:59:45 AppleTV CLTM[18] <Notice>: CLTM: resetting temps: now = 1449683985, last update = 1449683760
Dec  9 09:59:53 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:53.716518 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 09:59:53 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:53.718331 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 09:59:53 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 09:59:53 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x147460c0 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 09:59:55 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:55.747149 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 09:59:55 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:55.748946 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 09:59:55 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 09:59:55 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x147e5070 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 09:59:57 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:57.737939 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 09:59:57 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:57.739727 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 09:59:57 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 09:59:57 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x147e0620 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: country: FR
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: enable_content_header_cache: true
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: stall_notification_intrplay: true
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: geolocation.locale: fr-FR
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: movie_peakbandwidth_multiplier: 1.0
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: movie_norminal_to_peak_bandwidth_multiplier: 1.5
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: support_assistive_audio: true
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: connection_timeout_slow: 16000
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: geolocation.status: ALLOW
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: prefetch_timeout_in_seconds: 60
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: manifest_expiration_in_seconds: 7200
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: geolocation.country: FR
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: connection_retries: 3
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: header_downloader_cache_size: 5
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: movie_iframe_bandwidth_multiplier: 0.3
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: ui_boot_url: http://api.netflix.com/users/USER_ID/menu?v=2.0
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: ui_boot_url: http://api.netflix.com/users/USER_ID/menu?v=2.0&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: ui_boot_url: http://api.netflix.com/users/USER_ID/menu?v=2.0&output=plist
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: cdn_open_connect_forced: 4,6
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: initial_bitrate_mask_wifi: 1750
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: support_title_audio_selection: true
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: selected_cdn_bandwidth_multiplier: 1.0
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: geolocation.language: fr
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: connection_timeout: 6000
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: user: 1
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&track_id=0
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&track_id=0&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&track_id=0&v=2.0
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&track_id=0&v=2.0&
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: generic_feed_url: http://api.netflix.com/users/USER_ID/aggregatelists?filters=http://api.netflix.com/categories/title_formats/instant&description=/top10&user=http://api.netflix.com/users/USER_ID&min_list_depth=1&max_list_depth=26&track_id=0&v=2.0&output=plist
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: geolocation: FR
Dec  9 09:59:59 AppleTV AppleTV[40] <Warning>: Setting preferredLanguage = (
        "fr-FR",
        fr,
        en
    )
Dec  9 09:59:59 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:59.858366 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 09:59:59 AppleTV CoreHAP[218] <Notice>: 2015-12-09 09:59:59.859904 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 09:59:59 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 09:59:59 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x147ce2c0 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 10:00:20 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 10:00:20 AppleTV backboardd[44] <Notice>: 2015-12-09 10:00:20.419223 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 10:00:20 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 105 ms (54, discoveryd)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 108 ms (22, sharingd)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 108 ms (50, awdd)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 108 ms (43, powerd)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 108 ms (43, powerd)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 109 ms (63, CommCenterClassi)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: PM response took 109 ms (66, coreduetd)
Dec  9 10:00:20 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.245320 wlan0.N[241901] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.245372 wlan0.N[241902] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.245404 wlan0.N[241903] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.272995 wlan0.A[241904] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.273044 wlan0.N[241905] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 10:00:20 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.368665 wlan0.A[241906] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.368741 wlan0.A[241907] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.368777 wlan0.A[241908] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 10:00:20 AppleTV kernel[0] <Notice>: 325442.544755 wlan0.N[241909] (ID=107) 
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: 325442.752647 wlan0.N[241910] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 507 ms
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: 325442.753098 wlan0.N[241911] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 508 ms
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 103 ms
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 503 ms
Dec  9 10:00:21 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 10:00:38 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 10:00:38 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 10:00:38 AppleTV kernel[0] <Notice>: 325443.730664 wlan0.N[241912] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:00:38 AppleTV kernel[0] <Notice>: 325443.730711 wlan0.N[241913] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 10:00:38 AppleTV kernel[0] <Notice>: 325443.730742 wlan0.N[241914] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 10:00:38 AppleTV backboardd[44] <Notice>: 2015-12-09 10:00:38.879927 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 10:00:38 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 10:00:38 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:00:38 AppleTV powerd[43] <Notice>: 
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 107 ms
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.012819 wlan0.N[241915] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.012882 wlan0.A[241916] AppleBCMWLANCore::parseBdcTags(): AppleBCMWLANCore::parseBdcTags <0x80194f80> len 8 Data :325444.012939 wlan0.A[241917] wlan  (22)
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: wlan 0000: 12 06 00 af 1b 13 72 13 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 42  | ......r.ps.......W...B
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.012992 wlan0.N[241918] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.013021 wlan0.A[241919] AppleBCMWLANCore::handleDataPacket():  Wake up packet received: Data
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.013145 wlan0.A[241920] wlan Wakeup Packet: (122)
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: wlan 0000: 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 42 ff ff ff ff ff ff 70 73 cb d8 ff fc 70 73 cb d8 ff fc  | ps.......W...B......ps....ps....
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: wlan 0032: 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73  | ps....ps....ps....ps....ps....ps
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: wlan 0064: cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8  | ....ps....ps....ps....ps....ps..
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: wlan 0096: ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 70 73 cb d8 ff fc 00 00 00 00 00 00  | ..ps....ps....ps..........
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.052585 wlan0.N[241921] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.052639 wlan0.A[241922] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 17553ms SleepDuration 18584ms DutyCycle 5.5% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xade43180 has been configured
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.089352 wlan0.A[241923] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.100530 wlan0.A[241924] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: 325444.436921 wlan0.A[241925] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: system wake events:wlan
Dec  9 10:00:39 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 10:00:39 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 10:00:39 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:00:39 AppleTV powerd[43] <Notice>: 
Dec  9 10:00:39 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 10:00:39 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xade43180 has been started
Dec  9 10:00:40 AppleTV kernel[0] <Notice>: en1 duplicate IP address 192.168.0.39 sent from address b8:8d:12:57:84:11
Dec  9 10:00:40 AppleTV configd[21] <Notice>: DHCP en1: defending IP 192.168.0.39 against BonjourSleepProxy b8:8d:12:57:84:11 1 (of 5)
Dec  9 10:01:08 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 10:01:08 AppleTV backboardd[44] <Notice>: 2015-12-09 10:01:08.894754 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 10:01:08 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 10:01:08 AppleTV kernel[0] <Notice>: PM response took 102 ms (50, awdd)
Dec  9 10:01:09 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325473.887411 wlan0.N[241926] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325473.887464 wlan0.N[241927] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325473.887499 wlan0.N[241928] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325473.916756 wlan0.A[241929] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325473.916809 wlan0.N[241930] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 10:01:09 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.012496 wlan0.A[241931] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.012572 wlan0.A[241932] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.012606 wlan0.A[241933] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.188380 wlan0.N[241934] (ID=107) 
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.396425 wlan0.N[241935] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 509 ms
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: 325474.396867 wlan0.N[241936] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 510 ms
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 10:01:09 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 104 ms
Dec  9 10:01:10 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 502 ms
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(1) to(2) async took 571 ms
Dec  9 10:01:10 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: 325475.945676 wlan0.N[241937] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: 325475.945724 wlan0.N[241938] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 10:02:23 AppleTV kernel[0] <Notice>: 325475.945755 wlan0.N[241939] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 10:02:23 AppleTV backboardd[44] <Notice>: 2015-12-09 10:02:23.879931 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 10:02:23 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 10:02:23 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:02:23 AppleTV powerd[43] <Notice>: 
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 107 ms
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.228076 wlan0.N[241940] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.228140 wlan0.A[241941] AppleBCMWLANCore::parseBdcTags(): AppleBCMWLANCore::parseBdcTags <0x80194f80> len 8 Data :325476.228197 wlan0.A[241942] wlan  (22)
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0000: 12 06 00 80 54 aa 73 13 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 00  | ....T.s.ps.......W....
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.228250 wlan0.N[241943] AppleBCMWLANCore::systemWokenByWiFi():  Wake reason = wlan 
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.228281 wlan0.A[241944] AppleBCMWLANCore::handleDataPacket():  Wake up packet received: Data
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.228423 wlan0.A[241945] wlan Wakeup Packet: (148)
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0000: 70 73 cb d8 ff fc b8 8d 12 57 84 11 08 00 45 00 00 86 03 f8 40 00 2a 06 d7 88 11 8f a2 93 c0 a8  | ps.......W....E.....@.*.........
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0032: 00 27 14 67 cf 50 d6 82 78 8a 75 5c 73 30 80 18 01 f5 3c 9a 00 00 01 01 08 0a 75 25 7d ee 16 bb  | .'.g.P..x.u\s0....<.......u%}...
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0064: eb 81 bd 33 8f 5d 64 46 75 6a 30 d6 97 b2 7a 93 a3 df 09 82 b5 19 ff cd 58 b1 aa 82 91 fd a6 74  | ...3.]dFuj0...z.........X......t
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0096: a5 d3 cb 49 20 1b df c9 fb 83 c6 f3 e0 77 79 86 c5 ec 5b a2 a9 d7 99 f1 88 ce ba 15 11 d0 b2 e2  | ...I ........wy...[.............
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: wlan 0128: 3c 87 f2 f8 d3 c9 c9 82 33 10 c1 6f c8 9a 09 31 06 b2 16 bf  | <.......3..o...1....
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.267757 wlan0.N[241946] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.267810 wlan0.A[241947] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 73254ms SleepDuration 75136ms DutyCycle 2.5% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb088dc00 has been configured
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.299591 wlan0.A[241948] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.315616 wlan0.A[241949] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: 325476.651632 wlan0.A[241950] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: system wake events:wlan
Dec  9 10:02:24 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 10:02:24 AppleTV powerd[43] <Notice>: 
Dec  9 10:02:24 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 10:02:24 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 10:02:24 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb088dc00 has been started
Dec  9 10:02:24 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 10:02:25 AppleTV CLTM[18] <Notice>: CLTM: resetting temps: now = 1449684145, last update = 1449684067
Dec  9 10:02:27 AppleTV CoreHAP[218] <Notice>: 2015-12-09 10:02:27.709219 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 10:02:27 AppleTV CoreHAP[218] <Notice>: 2015-12-09 10:02:27.711420 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 10:02:27 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 10:02:27 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x1475fae0 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 10:02:29 AppleTV CoreHAP[218] <Notice>: 2015-12-09 10:02:29.859527 AM [Pairing] ### Pair-verify client state 2 failed: -6754/0xFFFFE59E kAuthenticationErr
Dec  9 10:02:29 AppleTV CoreHAP[218] <Notice>: 2015-12-09 10:02:29.861340 AM [BonjourBrowser] Reconfirming PTR for Hautomation._hap._tcp.local. on interface 3
Dec  9 10:02:29 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer::ProcessEvent unknown event type: 7!
Dec  9 10:02:29 AppleTV homed[218] <Error>: Failed to complete pair-verify - error: Error Domain=HMErrorDomain Code=55 "Failed with OSStatus code -6754 (kAuthenticationErr)" UserInfo=0x1475f730 {NSLocalizedDescription=Failed with OSStatus code -6754 (kAuthenticationErr)}
Dec  9 10:02:50 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 10:02:50 AppleTV backboardd[44] <Notice>: 2015-12-09 10:02:50.490698 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 10:02:50 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 10:02:50 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.685135 wlan0.N[241951] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.685190 wlan0.N[241952] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.685223 wlan0.N[241953] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.731760 wlan0.A[241954] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.731811 wlan0.N[241955] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 10:02:50 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.827414 wlan0.A[241956] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.827492 wlan0.A[241957] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325502.827528 wlan0.A[241958] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 10:02:50 AppleTV kernel[0] <Notice>: 325503.003413 wlan0.N[241959] (ID=107) 
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: 325503.211417 wlan0.N[241960] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 526 ms
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: 325503.211874 wlan0.N[241961] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 527 ms
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 102 ms
Dec  9 10:02:51 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 502 ms
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(1) to(2) async took 571 ms
Dec  9 10:02:52 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: 325504.760612 wlan0.N[241962] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: 325504.760658 wlan0.N[241963] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 10:03:37 AppleTV kernel[0] <Notice>: 325504.760689 wlan0.N[241964] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 10:03:37 AppleTV backboardd[44] <Notice>: 2015-12-09 10:03:37.879958 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 10:03:37 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 10:03:37 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:03:37 AppleTV powerd[43] <Notice>: 
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 107 ms
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: 325505.082438 wlan0.N[241965] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: 325505.082495 wlan0.A[241966] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 46497ms SleepDuration 47536ms DutyCycle 2.1% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb293f300 has been configured
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: 325505.114517 wlan0.A[241967] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: 325505.130539 wlan0.A[241968] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 10:03:38 AppleTV discoveryd[54] <Warning>: Basic Bonjour,Warn Warning: IPv6 listener multicast send failed awdl0, 49
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: 325505.474679 wlan0.A[241969] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 810 ms
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: system wake events:
Dec  9 10:03:38 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 0
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType Detached
Dec  9 10:03:38 AppleTV powerd[43] <Notice>: 
Dec  9 10:03:38 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 10:03:38 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 10:03:38 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xb293f300 has been started
Dec  9 10:03:38 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 10:04:07 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: CanSystemSleep
Dec  9 10:04:07 AppleTV backboardd[44] <Notice>: 2015-12-09 10:04:07.894381 AM [AppleTVIR] Power: SystemWillSleep
Dec  9 10:04:07 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillSleep
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: PM response took 100 ms (54, discoveryd)
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: PM response took 104 ms (50, awdd)
Dec  9 10:04:08 AppleTV powerd[43] <Notice>: Entering Sleep state due to 'Idle Sleep': Using AC (Charge:0%) 
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325534.909782 wlan0.N[241970] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x30005, dev 0x9bd3c000 (this 0, provider 1)
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325534.909836 wlan0.N[241971] AppleBCMWLANCore::powerOffSystem():  : Powering Off and sleeping
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325534.909869 wlan0.N[241972] AppleBCMWLANCore::powerOff():  fStateFlags(30004)
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325534.938677 wlan0.A[241973] AppleBCMWLANCore::numActiveNATKeepAlives(): NAT Keep Alive Count Returned from XNU is 0
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325534.938728 wlan0.N[241974] AppleBCMWLANCore::validAssociationForWoW(): DHCP Wake event 1 IsHotSpot 0 activeNATKeepAlives 0 PrimaryInf 1 LPAS 0
Dec  9 10:04:08 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 0, reason -528345084, subreason 0
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.034361 wlan0.A[241975] AppleBCMWLANCore::configureWoWEntry(): NPS mode not Supported, Fall-back to default configuration
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.034438 wlan0.A[241976] AppleBCMWLANCore::configureKeepAlives(): Keep Alive Count Returned from XNU is 0
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.034472 wlan0.A[241977] AppleBCMWLANCore::configureWoWEntry(): Configuring Keep-Alive Not Supported
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.210321 wlan0.N[241978] (ID=107) 
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.418374 wlan0.N[241979] AppleBCMWLANCore::powerOff():  Associated & WoW enabled, WiFi will remain up
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(0) to(0) async took 508 ms
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: 325535.418821 wlan0.N[241980] AppleBCMWLANCore::setPowerStateGated():   powerState 0, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: wlan::powerStateChange type(0) to(2) async took 509 ms
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 0 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 0
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(0) async took 101 ms
Dec  9 10:04:08 AppleTV kernel[0] <Notice>: EmbeddedIOP::powerStateChange type(2) to(0) async took 103 ms
Dec  9 10:04:09 AppleTV kernel[0] <Notice>: lan0::powerStateChange type(0) to(0) async took 503 ms
Dec  9 10:04:09 AppleTV kernel[0] <Notice>: virtual void AppleRGBOUT::do_power_state_change_gated(): fSoft: 1 fHard: 1 swapBusy: 0 pendingSwap: 0x0 fController: 0 -> 1
Dec  9 10:04:19 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated()
Dec  9 10:04:19 AppleTV kernel[0] <Notice>: bool AppleRGBOUT::power_up_hardware_gated(), could not enable RGBOUT. Mode is set to DP but could not locate DP driver
Dec  9 10:04:19 AppleTV kernel[0] <Notice>: 325536.395682 wlan0.N[241981] AppleBCMWLANCore::setPowerStateGated():   powerState 1, fStateFlags 0x20000, dev 0x8032b000 (this 1, provider 0)
Dec  9 10:04:19 AppleTV kernel[0] <Notice>: 325536.395729 wlan0.N[241982] AppleBCMWLANCore::powerOnSystem():  : Powering On
Dec  9 10:04:19 AppleTV kernel[0] <Notice>: 325536.395759 wlan0.N[241983] AppleBCMWLANCore::powerOn():  stateFlags(20001)
Dec  9 10:04:19 AppleTV backboardd[44] <Notice>: 2015-12-09 10:04:19.879936 AM [AppleTVIR] Power: SystemWillPowerOn
Dec  9 10:04:19 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemWillPowerOn
Dec  9 10:04:19 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:04:19 AppleTV powerd[43] <Notice>: 
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::message: provider messsaged with kAppleUSBHSICMessageDeviceReadySet
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleIOPFMI::powerStateChange type(2) to(1) async took 107 ms
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::powerStateChange type(1) to(1) async took 108 ms
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::setMulticastList: failed
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: 325536.717648 wlan0.N[241984] AppleBCMWLANCore::calculateAvailableRoamTime(): available roam time for WoW is -1 secs
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: 325536.717707 wlan0.A[241985] AppleBCMWLANCore::processPowerStatsChangesInSleep(): WOW (0x6) power report: MpcDuration 0ms PmDuration 11293ms SleepDuration 12144ms DutyCycle 7.0% RoamScanDuration 0ms ConnectTime 0ms AvailableRoamTime -1ms
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::start: RMT_WKP BOM stuffing *IS NOT SET* on this device, if you're testing for power peformance please read: <rdar://problem/10086065>
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa99d1480 has been configured
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: 325536.749600 wlan0.A[241986] AppleBCMWLANCore::powerOn():  Tearing down WoW mode
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: 325536.765542 wlan0.A[241987] AppleBCMWLANCore::configureWoWExit(): WNM is Not Supported
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: 325537.101675 wlan0.A[241988] AppleBCMWLANProximityInterface::doSetSyncState(): Cancelled fAWDLOffTimer
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: calling linkStateChange()
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: en1: BSSID changed to 00:24:d4:e5:ea:34
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleBCMWLANCore::powerStateChange type(2) to(1) async took 802 ms
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: system wake events:
Dec  9 10:04:20 AppleTV powerd[43] <Notice>: Wake [CDNVA] due to /: Using AC (Charge:0%)
Dec  9 10:04:20 AppleTV powerd[43] <Notice>: 
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetDevice::openGated: Opening LAN9730 device
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleHSICEthernetController::usbNotifier: AppleHSICEthernetDevice service 0xa99d1480 has been started
Dec  9 10:04:20 AppleTV wifid[56] <Error>: _dispatchDriverAvailable: available 1, reason -528345085, subreason 0
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableDetect 1
Dec  9 10:04:20 AppleTV kernel[0] <Notice>: AppleARMPMUCharger: AppleUSBCableType USBHost
Dec  9 10:04:20 AppleTV AppleTV[40] <Warning>: T:[Main] POWER : Got IOPM message: SystemHasPoweredOn (wake display on PowerOn: NO)
Dec  9 10:04:20 AppleTV discoveryd[54] <Notice>: Basic DNSResolver UDNSServer:: PowerState is Wakeup
Dec  9 10:04:26 AppleTV syslog_relay[110] <Notice>: syslog_relay read 51 total bytes: 
    ========================
    ASL is here to serve you
Dec  9 10:04:26 AppleTV syslog_relay[110] <Notice>: syslog_relay found the ASL prompt. Starting...
Dec  9 10:04:36 AppleTV kernel[0] <Notice>: en1 duplicate IP address 192.168.0.39 sent from address b8:8d:12:57:84:11
Dec  9 10:04:36 AppleTV configd[21] <Notice>: DHCP en1: defending IP 192.168.0.39 against BonjourSleepProxy b8:8d:12:57:84:11 1 (of 5)
KhaosT commented 8 years ago

@WoodySlum From your log, it looks like hap-nodejs created a new key pair when you launch the server and iOS failed to start the connection because the key pair changed.

KhaosT commented 8 years ago

@WoodySlum if possible, can you change the username for the accessory and then pair it with iOS and post content of ./persist/AccessoryInfo.username ?

WoodySlum commented 8 years ago

Here is the content of AccessoryInfo :

{"displayName":"Hautomation","port":51826,"category":1,"pincode":"987-82-695","signSk":"6b40ecbc1c43db984af7441b80a69c9c83d4c434aa28ed67f952db83bb2b32a1cdd2efe1266961b1b1c7129bff4f5ecb17e951379950c72356142045b419faad","signPk":"cdd2efe1266961b1b1c7129bff4f5ecb17e951379950c72356142045b419faad","pairedClients":{"B3ACA3F9-478A-4925-98CB-88F208460A15":"99b3ee5f0de48071db566d33caab8506fa35e3c0542801feec1456e6a8709429","F931633D-04A5-4492-BB95-E6D4115CCD3B":"019c118eabd84a15f8eb22c6063d5608f3d29db76505ac0db7849a7bf5427ccb","8C8238AD-29B1-4427-94F5-9AF2FCB30A4E":"5b6394f613168838ccd55625ac7b3ada02b65e3fb6005bb0b7c12e2cddc45184"},"configVersion":2,"configHash":"e47e4f6824d3c2d3f653ebccbbf0112efd7571fb"}

I also checked the logs and have the following error :

*** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
*** WARNING *** Please fix your application to use the native API of Avahi!
*** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node>
*** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
*** WARNING *** Please fix your application to use the native API of Avahi!
*** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister>
Wed, 09 Dec 2015 21:10:50 GMT Accessory [Hautomation] Creating new AccessoryInfo for our HAP server
events.js:85
      throw er; // Unhandled 'error' event
            ^
Error: listen EADDRINUSE
    at exports._errnoException (util.js:746:11)
    at Server._listen2 (net.js:1156:14)
    at listen (net.js:1182:10)
    at Server.listen (net.js:1267:5)
    at EventedHTTPServer.listen (/home/pi/hautomation/services/siri/HAP-NodeJS/lib/util/eventedhttp.js:58:19)
    at HAPServer.listen (/home/pi/hautomation/services/siri/HAP-NodeJS/lib/HAPServer.js:138:20)
    at Bridge.Accessory.publish (/home/pi/hautomation/services/siri/HAP-NodeJS/lib/Accessory.js:347:16)
    at Object.<anonymous> (/home/pi/hautomation/services/siri/HAP-NodeJS/BridgedCore.js:33:8)
    at Module._compile (module.js:460:26)
    at Object.Module._extensions..js (module.js:478:10)
KhaosT commented 8 years ago

@WoodySlum That error is caused by port in use shouldn't matter. What I don't understand is why every time when you boot up the server, it tries to recreate the accessory info object. If possible, can you post your accessory implementation somewhere?

WoodySlum commented 8 years ago

This is strange. If I check now the content of AccessoryInfo in persist folder, the file is empty. I have a mechanism to empty the persist folder when I'm pairing HomeKit. I'll check if there's no bug on this side.

eddydas commented 8 years ago

I had a similar issue just now.

But it turns out that I start the HAP-NodeJS thing from /etc/rc.local by /usr/bin/node /home/pi/hap/BridgedCore.js &

But rc.local is run with ~root as the PWD, and so it will not pick up /home/pi/hap/persist folder. Instead, it will spawn a new folder at ~root/persist. That's why it "lost the pairing". So what I did was changing the line to cd /home/pi/hap && /usr/bin/node BridgedCore.js &

Hope it helps someone out there.