-- Logs begin at Mon 2024-05-27 12:25:26 UTC, end at Tue 2024-08-27 02:53:59 UTC. -- Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:01 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:01 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:01 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:03 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:03 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:03 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:03 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:03 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:06 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:06 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:06 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:08 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:08 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:08 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:16 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:16 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:16 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:17 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:17 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:17 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:17 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:17 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:19 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:19 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:19 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:20 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:20 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:20 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:20 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:20 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:21 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:21 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:21 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:24 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:24 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:24 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:29 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:29 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:29 opera shairport-sync[1567]: {"time":1724727104660,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Aug 27 02:52:29 opera shairport-sync[1567]: Dload Upload Total Spent Left Speed Aug 27 02:52:29 opera volumio[1140]: info: Aug 27 02:52:29 opera volumio[1140]: ---------------------------- Client requests Stop Airplay Playback Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Aug 27 02:52:29 opera volumio[1140]: info: Airplay playback stop Aug 27 02:52:29 opera volumio[1140]: info: Airplay Playback Stopped, clearing state Aug 27 02:52:29 opera volumio[1140]: info: CoreStateMachine::resetVolumioState Aug 27 02:52:29 opera volumio[1140]: info: CoreStateMachine::getcurrentVolume Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 02:52:29 opera shairport-sync[1567]: [155B blob data] Aug 27 02:52:29 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:29 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:37 opera volumio[1140]: info: Airplay started streaming, receiving metadatas Aug 27 02:52:37 opera volumio[1140]: info: Airplay started streaming, receiving metadatas Aug 27 02:52:37 opera shairport-sync[1567]: {"time":1724727149037,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Aug 27 02:52:37 opera shairport-sync[1567]: Dload Upload Total Spent Left Speed Aug 27 02:52:37 opera volumio[1140]: info: Aug 27 02:52:37 opera volumio[1140]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 27 02:52:37 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 27 02:52:37 opera volumio[1140]: info: Airplay playback start Aug 27 02:52:37 opera shairport-sync[1567]: [155B blob data] Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:38 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:38 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:38 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:38 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:39 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:39 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:39 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:39 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:39 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:44 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:44 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:44 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:53 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:53 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:53 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:54 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:54 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:55 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:55 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:55 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:52:56 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:52:56 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:07 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:07 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:07 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:08 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:08 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:08 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:08 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:09 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:09 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:09 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:09 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:21 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::servicePushState Aug 27 02:53:21 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:21 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:46 opera kernel: rtk_btcoex: hci accept connreq, notify wifi page start Aug 27 02:53:46 opera kernel: rtk_btcoex: notify wifi page success end Aug 27 02:53:46 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:46 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x0308 Aug 27 02:53:46 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Aug 27 02:53:46 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:46 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0308, result 0x0000 Aug 27 02:53:47 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Aug 27 02:53:47 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0040, scid 0x0308 Aug 27 02:53:47 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0040, scid 0x0308, dir 0 Aug 27 02:53:47 opera kernel: rtk_btcoex: io cap req, notify wifi pair start Aug 27 02:53:48 opera kernel: rtk_btcoex: link key notify, notify wifi pair end Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x0408 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0408, result 0x0001 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x0041 Aug 27 02:53:48 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0001, dcid 0x070b, scid 0x0041, result 0x0000 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0017, scid 0x0509 Aug 27 02:53:48 opera kernel: rtk_btcoex: PSM(0x0017) do not need parse Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0042, scid 0x0509, result 0x0001 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x001f, scid 0x060a Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0043, scid 0x060a, result 0x0000 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap connection success, update connection Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 3 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x08 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x08 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Aug 27 02:53:48 opera bluetoothd[865]: GAP and GATT are mandatory Aug 27 02:53:48 opera bluetoothd[865]: gap-gatt-profile profile probe failed for 18:7E:B9:7C:F6:44 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0408, result 0x0000 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap connection success, update connection Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Aug 27 02:53:48 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Aug 27 02:53:48 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:48 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0042, scid 0x0509, result 0x0000 Aug 27 02:53:49 opera kernel: input: 18:7E:B9:7C:F6:44 as /devices/virtual/input/input4 Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x001b, scid 0x080c Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:49 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0044, scid 0x080c, result 0x0001 Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:49 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0044, scid 0x080c, result 0x0000 Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap connection success, update connection Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 3 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 2 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:49 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x090d Aug 27 02:53:49 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Aug 27 02:53:49 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:49 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x090d, result 0x0000 Aug 27 02:53:49 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 8 Aug 27 02:53:49 opera kernel: rtk_btcoex: timeout_handler: pan idle->busy! Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Aug 27 02:53:49 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 8 Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x08 Aug 27 02:53:49 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Aug 27 02:53:49 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0045, scid 0x090d Aug 27 02:53:50 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0045, scid 0x090d, dir 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x0a0e Aug 27 02:53:50 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:50 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x0a0e, result 0x0001 Aug 27 02:53:50 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Aug 27 02:53:50 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x0a0e, result 0x0000 Aug 27 02:53:50 opera kernel: rtk_btcoex: l2cap connection success, update connection Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 2 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Aug 27 02:53:50 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Aug 27 02:53:50 opera bluetoothd[865]: Invalid folder length Aug 27 02:53:50 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 10 Aug 27 02:53:50 opera volumio[1140]: BT PLUGIN MESSAGE: Duration is 199951 Aug 27 02:53:50 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Aug 27 02:53:51 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 1 Aug 27 02:53:51 opera kernel: rtk_btcoex: TX l2cap disconn req, hndl 0x0001, dcid 0x070b, scid 0x0041 Aug 27 02:53:51 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x070b, scid 0x0041, dir 1 Aug 27 02:53:51 opera volumio[1140]: BT PLUGIN MESSAGE: Duration is 199951 Aug 27 02:53:51 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 7 Aug 27 02:53:51 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Aug 27 02:53:52 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:52 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan busy->idle! Aug 27 02:53:52 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Aug 27 02:53:52 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 0 Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Aug 27 02:53:52 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Aug 27 02:53:52 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Aug 27 02:53:53 opera volumio[1140]: info: VolumeController::SetAlsaVolume29 Aug 27 02:53:53 opera volumio[1140]: BT PLUGIN MESSAGE: Volume set to 29 Aug 27 02:53:53 opera volumio[1140]: info: CoreStateMachine::pushState Aug 27 02:53:53 opera volumio[1140]: info: CoreCommandRouter::volumioPushState Aug 27 02:53:53 opera volumio[1140]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 02:53:53 opera bluetoothd[865]: /org/bluez/hci0/dev_18_7E_B9_7C_F6_44/fd0: fd(24) ready Aug 27 02:53:53 opera volumio[1140]: ------------------------------------ BT MESSAGE: BT STATUS: pending Aug 27 02:53:53 opera volumio[1140]: ------------------------------------ BT MESSAGE: BT STATUS: active Aug 27 02:53:53 opera volumio[1140]: BT PLUGIN MESSAGE: Playing: true Aug 27 02:53:53 opera volumio[1140]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Aug 27 02:53:53 opera volumio[1140]: BT PLUGIN MESSAGE: STATE:undefined Aug 27 02:53:53 opera volumio[1140]: info: CoreCommandRouter::volumioStop Aug 27 02:53:53 opera volumio[1140]: info: CoreStateMachine::stop Aug 27 02:53:53 opera volumio[1140]: info: CoreStateMachine::serviceStop Aug 27 02:53:53 opera volumio[1140]: info: CoreCommandRouter::serviceStop Aug 27 02:53:53 opera volumio[1140]: info: Airplay Stop Aug 27 02:53:53 opera volumio[1140]: info: Stopping Airplay Playback and sending pause command to client via USR2 Aug 27 02:53:53 opera sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1567 Aug 27 02:53:53 opera sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 02:53:53 opera sudo[3710]: pam_unix(sudo:session): session closed for user root Aug 27 02:53:53 opera shairport-sync[1567]: {"time":1724727157961,"response":"startAirplayPlayback Success"} Aug 27 02:53:53 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Aug 27 02:53:53 opera systemd[1]: Unit shairport-sync.service entered failed state. Aug 27 02:53:53 opera volumio[1140]: info: Shairport-Sync paused with USR2 Aug 27 02:53:53 opera volumio[1140]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Aug 27 02:53:53 opera volumio[1140]: info: Enabling Bluetooth Audio Output Aug 27 02:53:53 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Aug 27 02:53:53 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 4 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x04 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtp: v 2, cc 0, pt 96 Aug 27 02:53:53 opera kernel: rtk_btcoex: syncword: 9c Aug 27 02:53:53 opera kernel: rtk_btcoex: freq 44.1kHz Aug 27 02:53:53 opera kernel: rtk_btcoex: blocks 16 Aug 27 02:53:53 opera kernel: rtk_btcoex: channel mode JOINT_STEREO Aug 27 02:53:53 opera kernel: rtk_btcoex: allocation method LOUDNESS Aug 27 02:53:53 opera kernel: rtk_btcoex: subbands 8 Aug 27 02:53:53 opera kernel: rtk_btcoex: bitpool 53 Aug 27 02:53:53 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc51 Aug 27 02:53:53 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:53 opera volumio[1140]: info: Bluetooth Audio successfully Enabled Aug 27 02:53:53 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 22 Aug 27 02:53:54 opera volumio[1140]: info: Restarting Shairport-Sync after stop Aug 27 02:53:54 opera sudo[3732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 02:53:54 opera sudo[3732]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 02:53:54 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 27 02:53:54 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 27 02:53:54 opera sudo[3732]: pam_unix(sudo:session): session closed for user root Aug 27 02:53:54 opera volumio[1140]: info: Shairport-Sync retarted Aug 27 02:53:54 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:54 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Aug 27 02:53:55 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:55 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Aug 27 02:53:56 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:56 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Aug 27 02:53:57 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:57 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Aug 27 02:53:58 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Aug 27 02:53:58 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Aug 27 02:53:58 opera volumio[1140]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 02:53:58 opera volumio[1140]: { Error: connect ETIMEDOUT 31.13.85.53:443 Aug 27 02:53:58 opera volumio[1140]: at Object._errnoException (util.js:1022:11) Aug 27 02:53:58 opera volumio[1140]: at _exceptionWithHostPort (util.js:1044:20) Aug 27 02:53:58 opera volumio[1140]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 27 02:53:58 opera volumio[1140]: code: 'ETIMEDOUT', Aug 27 02:53:58 opera volumio[1140]: errno: 'ETIMEDOUT', Aug 27 02:53:58 opera volumio[1140]: syscall: 'connect', Aug 27 02:53:58 opera volumio[1140]: address: '31.13.85.53', Aug 27 02:53:58 opera volumio[1140]: port: 443 } Aug 27 02:53:58 opera volumio[1140]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 02:53:59 opera sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-27 02:52 Aug 27 02:53:59 opera sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"