-- Logs begin at Fri 2021-10-01 14:18:39 UTC, end at Fri 2021-10-01 14:24:38 UTC. -- Oct 01 14:23:26 opera kernel: rtk_btcoex: hci accept connreq, notify wifi page start Oct 01 14:23:26 opera kernel: rtk_btcoex: notify wifi page success end Oct 01 14:23:26 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 01 14:23:26 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x0040 Oct 01 14:23:26 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 01 14:23:26 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:26 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0040, result 0x0000 Oct 01 14:23:26 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 01 14:23:26 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0040, scid 0x0040 Oct 01 14:23:26 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0040, scid 0x0040, dir 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x0041 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0041, result 0x0001 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0040, scid 0x0041, result 0x0000 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap connection success, update connection Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x04 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Oct 01 14:23:27 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0019, scid 0x0042 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0041, scid 0x0042, result 0x0001 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:27 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0041, scid 0x0042, result 0x0000 Oct 01 14:23:27 opera kernel: rtk_btcoex: l2cap connection success, update connection Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 2 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x04 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Oct 01 14:23:27 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Oct 01 14:23:28 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Oct 01 14:23:29 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0001, PSM 0x0017, scid 0x0042 Oct 01 14:23:29 opera kernel: rtk_btcoex: PSM(0x0017) do not need parse Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0001, dcid 0x0043, scid 0x0042, result 0x0000 Oct 01 14:23:29 opera kernel: input: F4:87:C5:B8:A2:CA as /devices/virtual/input/input4 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 1 Oct 01 14:23:29 opera kernel: rtk_btcoex: TX l2cap conn req, hndl 0x0001, PSM 0x001b, scid 0x0043 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 2, len 16, out 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: RX l2cap conn req, hndl 0x0001, PSM 0x0001, scid 0x0044 Oct 01 14:23:29 opera kernel: rtk_btcoex: PSM(0x0001) do not need parse Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 1 Oct 01 14:23:29 opera kernel: rtk_btcoex: TX l2cap conn rsp, hndl 0x0001, dcid 0x0044, scid 0x0044, result 0x0000 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 3, len 20, out 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: RX l2cap conn rsp, hndl 0x0001, dcid 0x0045, scid 0x0043, result 0x0000 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap connection success, update connection Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: is_add 1, profile_index 3 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_bitmap 0x0c Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[0] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[1] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[2] = 2 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[3] = 1 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[4] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[5] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[6] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: update_profile_connection: btrtl_coex.profile_refcount[7] = 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Oct 01 14:23:29 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:29 opera kernel: rtk_btcoex: l2cap op 6, len 16, out 0 Oct 01 14:23:29 opera kernel: rtk_btcoex: RX l2cap disconn req, hndl 0x0001, dcid 0x0044, scid 0x0044 Oct 01 14:23:29 opera kernel: rtk_btcoex: handle_l2cap_discon_req: handle 0x0001, dcid 0x0044, scid 0x0044, dir 0 Oct 01 14:23:29 opera volumio[950]: BT PLUGIN MESSAGE: Duration is 0 Oct 01 14:23:30 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:30 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 9 Oct 01 14:23:30 opera kernel: rtk_btcoex: timeout_handler: pan idle->busy! Oct 01 14:23:30 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Oct 01 14:23:30 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 8 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x08 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:30 opera bluetoothd[864]: /org/bluez/hci0/dev_F4_87_C5_B8_A2_CA/fd0: fd(19) ready Oct 01 14:23:30 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: pending Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: Playing: false Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:30 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: active Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: Playing: true Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:30 opera volumio[950]: info: CoreCommandRouter::volumioStop Oct 01 14:23:30 opera volumio[950]: info: CoreStateMachine::stop Oct 01 14:23:30 opera volumio[950]: info: CoreStateMachine::serviceStop Oct 01 14:23:30 opera volumio[950]: info: CoreCommandRouter::serviceStop Oct 01 14:23:30 opera volumio[950]: info: Airplay Stop Oct 01 14:23:30 opera volumio[950]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 01 14:23:30 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: playing Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: Playing: true Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: airplay_emulation Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:30 opera volumio[950]: info: CoreCommandRouter::volumioStop Oct 01 14:23:30 opera volumio[950]: info: CoreStateMachine::stop Oct 01 14:23:30 opera volumio[950]: info: CoreStateMachine::serviceStop Oct 01 14:23:30 opera volumio[950]: info: CoreCommandRouter::serviceStop Oct 01 14:23:30 opera volumio[950]: info: Airplay Stop Oct 01 14:23:30 opera volumio[950]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 01 14:23:30 opera sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1463 Oct 01 14:23:30 opera sudo[2395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1463 Oct 01 14:23:30 opera sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 14:23:30 opera sudo[2395]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 14:23:30 opera sudo[2395]: pam_unix(sudo:session): session closed for user root Oct 01 14:23:30 opera sudo[2394]: pam_unix(sudo:session): session closed for user root Oct 01 14:23:30 opera shairport-sync[1463]: {"time":1633098148562,"response":"airplayActive Success"} Oct 01 14:23:30 opera systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 Oct 01 14:23:30 opera systemd[1]: Unit shairport-sync.service entered failed state. Oct 01 14:23:30 opera volumio[950]: info: Shairport-Sync paused with USR2 Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Oct 01 14:23:30 opera volumio[950]: info: Enabling Bluetooth Audio Output Oct 01 14:23:30 opera volumio[950]: info: Shairport-Sync paused with USR2 Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: BLUETOOTH: Starting Playback Routine Oct 01 14:23:30 opera volumio[950]: info: Enabling Bluetooth Audio Output Oct 01 14:23:30 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Oct 01 14:23:30 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = c Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x0c Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtp: v 2, cc 0, pt 96 Oct 01 14:23:30 opera kernel: rtk_btcoex: syncword: 9c Oct 01 14:23:30 opera kernel: rtk_btcoex: freq 44.1kHz Oct 01 14:23:30 opera kernel: rtk_btcoex: blocks 16 Oct 01 14:23:30 opera kernel: rtk_btcoex: channel mode JOINT_STEREO Oct 01 14:23:30 opera kernel: rtk_btcoex: allocation method LOUDNESS Oct 01 14:23:30 opera kernel: rtk_btcoex: subbands 8 Oct 01 14:23:30 opera kernel: rtk_btcoex: bitpool 35 Oct 01 14:23:30 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc51 Oct 01 14:23:30 opera pulseaudio[1510]: Failed to load module "module-alsa-sink" (argument: "device=plughw:5,0"): initialization failed. Oct 01 14:23:30 opera volumio[950]: error: Cannot load module of audio hardware: Error: Command failed: /usr/bin/pactl load-module module-alsa-sink device=plughw:5,0 Oct 01 14:23:30 opera volumio[950]: No protocol specified Oct 01 14:23:30 opera volumio[950]: xcb_connection_has_error() returned true Oct 01 14:23:30 opera volumio[950]: Failure: Module initialization failed Oct 01 14:23:30 opera volumio[950]: No protocol specified Oct 01 14:23:30 opera volumio[950]: xcb_connection_has_error() returned true Oct 01 14:23:30 opera volumio[950]: BT PLUGIN MESSAGE: SINK LOADED: 1 Oct 01 14:23:30 opera volumio[950]: info: Alsa Sink Already Loaded Oct 01 14:23:30 opera volumio[950]: info: Bluetooth Audio successfully Enabled Oct 01 14:23:31 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 16 Oct 01 14:23:31 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:31 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan busy->idle! Oct 01 14:23:31 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Oct 01 14:23:31 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 4 Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x04 Oct 01 14:23:31 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:31 opera volumio[950]: info: Restarting Shairport-Sync after stop Oct 01 14:23:31 opera sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 14:23:31 opera sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 14:23:31 opera volumio[950]: info: Restarting Shairport-Sync after stop Oct 01 14:23:31 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 14:23:31 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 14:23:31 opera sudo[2434]: pam_unix(sudo:session): session closed for user root Oct 01 14:23:31 opera volumio[950]: info: Shairport-Sync retarted Oct 01 14:23:31 opera sudo[2447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 14:23:31 opera sudo[2447]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 14:23:31 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 01 14:23:31 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: Playing: false Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: bluetooth Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::servicePushState Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::pushState Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::volumioPushState Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 14:23:31 opera volumio[950]: verbose: UNSET VOLATILE Oct 01 14:23:31 opera volumio[950]: info: Bluetooth Detach Command Oct 01 14:23:31 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 14:23:31 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 14:23:31 opera sudo[2447]: pam_unix(sudo:session): session closed for user root Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::resetVolumioState Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::getcurrentVolume Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::pushState Oct 01 14:23:31 opera volumio[950]: info: CorePlayQueue::getTrack 0 Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::volumioPushState Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 14:23:31 opera volumio[950]: info: CoreCommandRouter::volumioStop Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::stop Oct 01 14:23:31 opera volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 01 14:23:31 opera volumio[950]: verbose: UNSET VOLATILE Oct 01 14:23:31 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: Playing: false Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 01 14:23:31 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:31 opera volumio[950]: info: Shairport-Sync retarted Oct 01 14:23:32 opera volumio[950]: info: Bluetooth Audio successfully Disabled Oct 01 14:23:32 opera volumio[950]: info: Bluetooth Successfully Detached Oct 01 14:23:32 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 34 Oct 01 14:23:32 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: paused Oct 01 14:23:32 opera volumio[950]: BT PLUGIN MESSAGE: Playing: false Oct 01 14:23:32 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 01 14:23:32 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:32 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:33 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 48 Oct 01 14:23:33 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:34 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 42 Oct 01 14:23:34 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:35 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 52 Oct 01 14:23:35 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:35 opera volumio[950]: ------------------------------------ BT MESSAGE: BT STATUS: idle Oct 01 14:23:35 opera volumio[950]: BT PLUGIN MESSAGE: Playing: false Oct 01 14:23:35 opera volumio[950]: BT PLUGIN MESSAGE: VOLATILE: undefined Oct 01 14:23:35 opera volumio[950]: BT PLUGIN MESSAGE: STATE:undefined Oct 01 14:23:36 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 21 Oct 01 14:23:36 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:37 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:37 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp busy->idle! Oct 01 14:23:37 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profie_bitmap = c Oct 01 14:23:37 opera kernel: rtk_btcoex: update_profile_state: btrtl_coex.profile_status = 0 Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: BufferSize 5 Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: NumberOfHandles 1 Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: handle 0x0001 Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_bitmap 0x0c Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_notify_profileinfo_to_fw: profile_status 0x00 Oct 01 14:23:37 opera kernel: rtk_btcoex: rtk_vendor_cmd_to_fw: opcode 0xfc19 Oct 01 14:23:37 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:38 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:38 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:39 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:39 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:40 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:40 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:41 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:41 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:42 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:42 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:42 opera kernel: rtk_btcoex: update_hid_active_state: handle 0x0001, interval 800 Oct 01 14:23:42 opera kernel: rtk_btcoex: HID not connected, nothing to be down Oct 01 14:23:43 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:43 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:44 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:44 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:45 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:45 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:46 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:46 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:47 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:47 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:48 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:48 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:49 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:49 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:50 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:50 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:51 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:51 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:52 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:52 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:53 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:53 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:54 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:54 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:55 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:55 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:56 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:56 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:57 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:57 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:58 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:58 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:23:59 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:23:59 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:00 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:00 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:01 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:01 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:02 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:02 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:03 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:03 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:04 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:04 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:05 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:05 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:06 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:06 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:07 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:07 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:08 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:08 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:09 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:09 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:10 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:10 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:11 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:11 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:12 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:12 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:13 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:13 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:14 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:14 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:15 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:15 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:16 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:16 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:17 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:17 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:18 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:18 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:19 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:19 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:20 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:20 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:21 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:21 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:22 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:22 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:23 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:23 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:24 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:24 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:25 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:25 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:26 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:26 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:27 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:27 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:28 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:28 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:29 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:29 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:30 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:30 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:31 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:31 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:32 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:32 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:33 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:33 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:34 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:34 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:35 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:35 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:36 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:36 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:37 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:37 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:38 opera kernel: rtk_btcoex: count_a2dp_packet_timeout: a2dp_packet_count 0 Oct 01 14:24:38 opera volumio[950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 14:24:38 opera volumio[950]: { Error: connect ETIMEDOUT 199.16.156.75:443 Oct 01 14:24:38 opera volumio[950]: at Object._errnoException (util.js:1022:11) Oct 01 14:24:38 opera volumio[950]: at _exceptionWithHostPort (util.js:1044:20) Oct 01 14:24:38 opera volumio[950]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 01 14:24:38 opera volumio[950]: code: 'ETIMEDOUT', Oct 01 14:24:38 opera volumio[950]: errno: 'ETIMEDOUT', Oct 01 14:24:38 opera volumio[950]: syscall: 'connect', Oct 01 14:24:38 opera volumio[950]: address: '199.16.156.75', Oct 01 14:24:38 opera volumio[950]: port: 443 } Oct 01 14:24:38 opera volumio[950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 14:24:38 opera kernel: rtk_btcoex: count_pan_packet_timeout: pan_packet_count 0 Oct 01 14:24:38 opera sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-10-01 14:23 Oct 01 14:24:38 opera sudo[2643]: 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="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 16:18:39 CEST 2021" VOLUMIO_VERSION="1.031" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="8579bfbfd528c599f57acb0dd7167775"