-- Logs begin at Tue 2025-06-03 06:12:56 UTC, end at Thu 2025-06-05 06:43:37 UTC. -- Jun 05 06:42:00 opera volumio[1243]: info: Airplay started streaming, receiving metadatas Jun 05 06:42:00 opera volumio[1243]: info: Airplay started streaming, receiving metadatas Jun 05 06:42:00 opera shairport-sync[1664]: {"time":1749105713392,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 05 06:42:00 opera shairport-sync[1664]: Dload Upload Total Spent Left Speed Jun 05 06:42:00 opera volumio[1243]: info: Jun 05 06:42:00 opera volumio[1243]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jun 05 06:42:00 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jun 05 06:42:00 opera volumio[1243]: info: Airplay playback start Jun 05 06:42:00 opera shairport-sync[1664]: [155B blob data] Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:01 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:01 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:01 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:01 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:01 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:01 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:01 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:02 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:02 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:02 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:02 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:02 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:42:02 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:42:02 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:42:02 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:02 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:42:02 opera volumio[1243]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joshua%20Hyslop/Westward/8c930c15-a314-4286-a100-b7730fb806ad.jpg' Jun 05 06:42:04 opera volumio[1243]: error: error Jun 05 06:42:48 opera systemd[1]: Started volumio-remote-updater-push.service. Jun 05 06:42:48 opera systemd[1]: Starting Multi-User System. Jun 05 06:42:48 opera systemd[1]: Reached target Multi-User System. Jun 05 06:42:48 opera systemd[1]: Starting Graphical Interface. Jun 05 06:42:48 opera systemd[1]: Reached target Graphical Interface. Jun 05 06:42:48 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 05 06:42:48 opera volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 05 06:42:48 opera systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 05 06:42:48 opera systemd[1]: Startup finished in 11.363s (kernel) + 2min 4.483s (userspace) = 2min 15.846s. Jun 05 06:42:54 opera volumio[1243]: error: error Jun 05 06:43:08 opera kernel: rtk_btcoex: hci accept connreq, notify wifi page start Jun 05 06:43:08 opera kernel: rtk_btcoex: notify wifi page success end Jun 05 06:43:08 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:08 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x1b08 Jun 05 06:43:08 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Jun 05 06:43:08 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:08 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x1b08, result 0x0000 Jun 05 06:43:08 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Jun 05 06:43:08 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0040, scid 0x1b08 Jun 05 06:43:08 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0040, scid 0x1b08, dir 0 Jun 05 06:43:08 opera kernel: rtk_btcoex: io cap req, notify wifi pair start Jun 05 06:43:09 opera kernel: rtk_btcoex: link key notify, notify wifi pair end Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x1c08 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x1c08, result 0x0001 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x0041 Jun 05 06:43:09 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0001, dcid 0x1f0b, scid 0x0041, result 0x0000 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0017, scid 0x1d09 Jun 05 06:43:09 opera kernel: rtk_btcoex: PSM(0x0017) do not need parse Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0042, scid 0x1d09, result 0x0001 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x001f, scid 0x1e0a Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0043, scid 0x1e0a, result 0x0000 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap connection success, update connection Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 3 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x08 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x08 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Jun 05 06:43:09 opera bluetoothd[888]: GAP and GATT are mandatory Jun 05 06:43:09 opera bluetoothd[888]: gap-gatt-profile profile probe failed for 54:33:CB:CC:CA:4B Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x1c08, result 0x0000 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap connection success, update connection Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Jun 05 06:43:09 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Jun 05 06:43:09 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:09 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0042, scid 0x1d09, result 0x0000 Jun 05 06:43:09 opera kernel: input: 54:33:CB:CC:CA:4B as /devices/virtual/input/input4 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x001b, scid 0x200c Jun 05 06:43:10 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0044, scid 0x200c, result 0x0001 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0044, scid 0x200c, result 0x0000 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap connection success, update connection Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 3 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 2 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x210d Jun 05 06:43:10 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Jun 05 06:43:10 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x210d, result 0x0000 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0045, scid 0x210d Jun 05 06:43:10 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0045, scid 0x210d, dir 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x220e Jun 05 06:43:10 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x220e, result 0x0001 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x220e, result 0x0000 Jun 05 06:43:10 opera kernel: rtk_btcoex: l2cap connection success, update connection Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 2 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Jun 05 06:43:10 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 9 Jun 05 06:43:10 opera kernel: rtk_btcoex: timeout_handler: pan idle->busy! Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Jun 05 06:43:10 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 8 Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x08 Jun 05 06:43:10 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:10 opera shairport-sync[1664]: {"time":1749105720643,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jun 05 06:43:10 opera shairport-sync[1664]: Dload Upload Total Spent Left Speed Jun 05 06:43:10 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:10 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:10 opera volumio[1243]: info: Jun 05 06:43:10 opera volumio[1243]: ---------------------------- Client requests Stop Airplay Playback Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jun 05 06:43:10 opera volumio[1243]: info: Airplay playback stop Jun 05 06:43:10 opera volumio[1243]: info: Airplay Playback Stopped, clearing state Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::resetVolumioState Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::getcurrentVolume Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 06:43:10 opera shairport-sync[1664]: [155B blob data] Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:10 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:10 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Jun 05 06:43:10 opera volumio[1243]: info: VolumeController::SetAlsaVolume29 Jun 05 06:43:10 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 29 Jun 05 06:43:10 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:10 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:10 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera bluetoothd[888]: /org/bluez/hci0/dev_54_33_CB_CC_CA_4B/fd0: fd(24) ready Jun 05 06:43:11 opera volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: pending Jun 05 06:43:11 opera volumio[1243]: ------------------------------------ BT MESSAGE: BT STATUS: active Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: Playing: true Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: STATE:undefined Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioStop Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::stop Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::serviceStop Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::serviceStop Jun 05 06:43:11 opera volumio[1243]: info: Airplay Stop Jun 05 06:43:11 opera volumio[1243]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 05 06:43:11 opera sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1664 Jun 05 06:43:11 opera sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 06:43:11 opera sudo[2126]: pam_unix(sudo:session): session closed for user root Jun 05 06:43:11 opera shairport-sync[1664]: {"time":1749105790819,"response":"stopAirplayPlayback Success"} Jun 05 06:43:11 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Jun 05 06:43:11 opera systemd[1]: Unit shairport-sync.service entered failed state. Jun 05 06:43:11 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Jun 05 06:43:11 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = c Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x0c Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Jun 05 06:43:11 opera kernel: rtk_btcoex: rtp: v 2, cc 0, pt 96 Jun 05 06:43:11 opera kernel: rtk_btcoex: syncword: 9c Jun 05 06:43:11 opera kernel: rtk_btcoex: freq 44.1kHz Jun 05 06:43:11 opera kernel: rtk_btcoex: blocks 16 Jun 05 06:43:11 opera kernel: rtk_btcoex: channel mode JOINT_STEREO Jun 05 06:43:11 opera kernel: rtk_btcoex: allocation method LOUDNESS Jun 05 06:43:11 opera kernel: rtk_btcoex: subbands 8 Jun 05 06:43:11 opera kernel: rtk_btcoex: bitpool 53 Jun 05 06:43:11 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc51 Jun 05 06:43:11 opera volumio[1243]: info: Shairport-Sync paused with USR2 Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Jun 05 06:43:11 opera volumio[1243]: info: Enabling Bluetooth Audio Output Jun 05 06:43:11 opera volumio[1243]: info: Bluetooth Audio successfully Enabled Jun 05 06:43:11 opera bluetoothd[888]: Invalid folder length Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: Duration is 261828 Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:11 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera volumio[1243]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joshua%20Hyslop/Westward/8c930c15-a314-4286-a100-b7730fb806ad.jpg' Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:11 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:11 opera volumio[1243]: BT PLUGIN MESSAGE: Duration is 261828 Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:11 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:11 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:11 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:11 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:11 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 15 Jun 05 06:43:11 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 1 Jun 05 06:43:11 opera kernel: rtk_btcoex: TX l2cap disconn req, hndl 0x0001, dcid 0x1f0b, scid 0x0041 Jun 05 06:43:11 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x1f0b, scid 0x0041, dir 1 Jun 05 06:43:11 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 55 Jun 05 06:43:12 opera volumio[1243]: info: Restarting Shairport-Sync after stop Jun 05 06:43:12 opera sudo[2147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 06:43:12 opera sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 05 06:43:12 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 05 06:43:12 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 05 06:43:12 opera sudo[2147]: pam_unix(sudo:session): session closed for user root Jun 05 06:43:12 opera volumio[1243]: info: Shairport-Sync retarted Jun 05 06:43:12 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:12 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan busy->idle! Jun 05 06:43:12 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Jun 05 06:43:12 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 4 Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x04 Jun 05 06:43:12 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Jun 05 06:43:12 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:13 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:13 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:14 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:14 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:15 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:15 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:16 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:16 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume29 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 29 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume26 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 26 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume23 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 23 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume20 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 20 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume17 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 17 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume12 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 12 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume10 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 10 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume7 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 7 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume6 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 6 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume4 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 4 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume3 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 3 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume0 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 0 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: VolumeController::SetAlsaVolume0 Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 0 Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: BT PLUGIN MESSAGE: Duration is 261828 Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:17 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:17 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:17 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:17 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Jun 05 06:43:18 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 4 Jun 05 06:43:18 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:19 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:19 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:20 opera volumio[1243]: BT PLUGIN MESSAGE: Duration is 261828 Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:20 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:20 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:20 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:20 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:20 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:20 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 3 Jun 05 06:43:20 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume5 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 5 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume11 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 11 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume18 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 18 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume24 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 24 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume30 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 30 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume37 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 37 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume43 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 43 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 1 Jun 05 06:43:21 opera volumio[1243]: info: VolumeController::SetAlsaVolume49 Jun 05 06:43:21 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 49 Jun 05 06:43:21 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:21 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:21 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:21 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:22 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:22 opera volumio[1243]: info: VolumeController::SetAlsaVolume55 Jun 05 06:43:22 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 55 Jun 05 06:43:22 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:22 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:22 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:22 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:22 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Jun 05 06:43:23 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:23 opera volumio[1243]: info: VolumeController::SetAlsaVolume62 Jun 05 06:43:23 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 62 Jun 05 06:43:23 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:23 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:23 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:23 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 70 Jun 05 06:43:23 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:24 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:24 opera volumio[1243]: info: VolumeController::SetAlsaVolume68 Jun 05 06:43:24 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 68 Jun 05 06:43:24 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:24 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:24 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:24 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:24 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:24 opera volumio[1243]: info: VolumeController::SetAlsaVolume74 Jun 05 06:43:24 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 74 Jun 05 06:43:24 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:24 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:24 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:24 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:25 opera volumio[1243]: info: VolumeController::SetAlsaVolume81 Jun 05 06:43:25 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 81 Jun 05 06:43:25 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:25 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:25 opera volumio[1243]: info: VolumeController::SetAlsaVolume87 Jun 05 06:43:25 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 87 Jun 05 06:43:25 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:25 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:25 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:25 opera volumio[1243]: info: VolumeController::SetAlsaVolume93 Jun 05 06:43:25 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 93 Jun 05 06:43:25 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:25 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:25 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:25 opera volumio[1243]: info: VolumeController::SetAlsaVolume100 Jun 05 06:43:25 opera volumio[1243]: BT PLUGIN MESSAGE: Volume set to 100 Jun 05 06:43:25 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:25 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:25 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:26 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:26 opera volumio[1243]: BT PLUGIN MESSAGE: Duration is 261828 Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:26 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:26 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::servicePushState Jun 05 06:43:26 opera volumio[1243]: info: CoreStateMachine::pushState Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::volumioPushState Jun 05 06:43:26 opera volumio[1243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 06:43:26 opera volumio[1243]: info: Updating RAAT Signal Path Jun 05 06:43:26 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:27 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 4 Jun 05 06:43:27 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:28 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:28 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:29 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:29 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:30 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:30 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Jun 05 06:43:31 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:31 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:32 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:32 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 67 Jun 05 06:43:33 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:33 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 71 Jun 05 06:43:34 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:34 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:35 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:35 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 68 Jun 05 06:43:36 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:36 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:37 opera volumio[1243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 06:43:37 opera volumio[1243]: { Error: connect ETIMEDOUT 108.160.167.165:443 Jun 05 06:43:37 opera volumio[1243]: at Object._errnoException (util.js:1022:11) Jun 05 06:43:37 opera volumio[1243]: at _exceptionWithHostPort (util.js:1044:20) Jun 05 06:43:37 opera volumio[1243]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jun 05 06:43:37 opera volumio[1243]: code: 'ETIMEDOUT', Jun 05 06:43:37 opera volumio[1243]: errno: 'ETIMEDOUT', Jun 05 06:43:37 opera volumio[1243]: syscall: 'connect', Jun 05 06:43:37 opera volumio[1243]: address: '108.160.167.165', Jun 05 06:43:37 opera volumio[1243]: port: 443 } Jun 05 06:43:37 opera volumio[1243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 06:43:37 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Jun 05 06:43:37 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 69 Jun 05 06:43:37 opera sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-05 06:42 Jun 05 06:43:37 opera sudo[2232]: 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"