-- Logs begin at Thu 2019-02-14 19:11:58 +09, end at Tue 2026-02-17 11:48:30 +09. -- Feb 17 11:47:02 volumio-10 volumio[1130]: info: MyVolumio login type: Token Feb 17 11:47:32 volumio-10 volumio[1130]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 17 11:47:32 volumio-10 volumio[1130]: info: Retrying Login Due to Network Error Feb 17 11:47:42 volumio-10 volumio[1130]: info: MyVolumio login type: Token Feb 17 11:47:43 volumio-10 volumio[1130]: error: MyVolumio Custom Token format not valid, refreshing it Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:47:44 volumio-10 volumio-remote-updater[610]: No test mode Feb 17 11:47:44 volumio-10 volumio-remote-updater[610]: No alpha test mode Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 11:47:44 volumio-10 volumio[1130]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 17 11:47:44 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 17 11:47:46 volumio-10 volumio[1130]: info: MyVolumio login type: Token Feb 17 11:47:48 volumio-10 volumio[1130]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 17 11:47:49 volumio-10 volumio[1130]: info: MyVolumio token set successfully Feb 17 11:47:49 volumio-10 volumio[1130]: info: MYVOLUMIO: Adding device Feb 17 11:47:49 volumio-10 volumio[1130]: info: MYVOLUMIO: Evaluating Server Feb 17 11:47:50 volumio-10 volumio[1130]: info: MyVolumio status changed Feb 17 11:47:50 volumio-10 volumio[1130]: info: Streaming services startup Feb 17 11:47:50 volumio-10 volumio[1130]: info: Starting Streaming Daemon Feb 17 11:47:50 volumio-10 volumio[1130]: info: Removing browser output: myVolumio user plan is not superstar Feb 17 11:47:50 volumio-10 volumio[1130]: info: Removing audio output: Feb 17 11:47:50 volumio-10 volumio[1130]: info: Stoppping Tunnel 1 Feb 17 11:47:50 volumio-10 sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 17 11:47:50 volumio-10 sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:47:50 volumio-10 sudo[3718]: pam_unix(sudo:session): session closed for user root Feb 17 11:47:50 volumio-10 sudo[3721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 17 11:47:50 volumio-10 sudo[3721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:47:50 volumio-10 volumio[1130]: error: Cannot start Volumio Streaming Daemon Feb 17 11:47:50 volumio-10 volumio[1130]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 17 11:47:50 volumio-10 volumio[1130]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 17 11:47:50 volumio-10 sudo[3721]: pam_unix(sudo:session): session closed for user root Feb 17 11:47:50 volumio-10 volumio[1130]: info: Remote SSH Stopped Feb 17 11:47:50 volumio-10 volumio[1130]: info: Setting Geolocation for MyVolumio to eu12 Feb 17 11:47:50 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:51 volumio-10 volumio[1130]: info: Updating MyVolumio device info Feb 17 11:47:51 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:52 volumio-10 volumio[1130]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 11:47:52 volumio-10 volumiologrotate[605]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 17 11:47:52 volumio-10 volumiologrotate[605]: ls: cannot access '10': No such file or directory Feb 17 11:47:52 volumio-10 volumio[1130]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 11:47:56 volumio-10 volumio[1130]: info: MYVOLUMIO: Adding device Feb 17 11:47:56 volumio-10 volumio[1130]: info: MYVOLUMIO: Evaluating Server Feb 17 11:47:57 volumio-10 volumio[1130]: info: Setting Geolocation for MyVolumio to eu12 Feb 17 11:47:57 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:57 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:57 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:58 volumio-10 volumio[1130]: info: Updating MyVolumio device info Feb 17 11:47:58 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 11:47:58 volumio-10 volumio[1130]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 17 11:47:58 volumio-10 volumio[1130]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 17 11:48:00 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 1 Feb 17 11:48:00 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:00 volumio-10 volumio[1130]: info: Prefetching next song Feb 17 11:48:00 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: sendMpdCommand addid "http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" took 2 milliseconds Feb 17 11:48:00 volumio-10 volumio[1130]: verbose: MPD COMMAND [object Object] Feb 17 11:48:00 volumio-10 volumio[1130]: verbose: MPD COMMAND [object Object] Feb 17 11:48:00 volumio-10 volumio[1130]: verbose: MPD COMMAND [object Object] Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 5ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 4ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 3ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 3ms Feb 17 11:48:00 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Feb 17 11:48:00 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:00 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 7ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: sendMpdCommand consume 1 took 7 milliseconds Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 6ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 5ms Feb 17 11:48:00 volumio-10 volumio[1130]: info: ------------------------------ 2ms Feb 17 11:48:04 volumio-10 volumio[1130]: info: CoreStateMachine::startPlaybackTimer Feb 17 11:48:04 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces state update: player Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::getState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces state update: player Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::getState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces state update: player Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::getState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces system playlist update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Ignoring MPD Status Update Feb 17 11:48:05 volumio-10 volumio[1130]: info: Feb 17 11:48:05 volumio-10 volumio[1130]: ---------------------------- MPD announces state update: player Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::getState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 6ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand status took 6 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 6ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand status took 5 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 4ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand status took 5 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 4ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand status took 3 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseState Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: ControllerMpd::parseTrackInfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::servicePushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"Jealousy","artist":"Udo Dirkschneider","album":"My Way","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: CURRENT POSITION 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState stateService play Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:48:05 volumio-10 volumio[1130]: info: Received an update from plugin. extracting info from payload Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::servicePushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"Jealousy","artist":"Udo Dirkschneider","album":"My Way","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: CURRENT POSITION 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState stateService play Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:48:05 volumio-10 volumio[1130]: info: Received an update from plugin. extracting info from payload Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::servicePushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"Jealousy","artist":"Udo Dirkschneider","album":"My Way","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: CURRENT POSITION 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState stateService play Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:48:05 volumio-10 volumio[1130]: info: Received an update from plugin. extracting info from payload Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: ControllerMpd::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::servicePushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getTrack 2 Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":340,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1015 Kbps","isStreaming":false,"title":"Jealousy","artist":"Udo Dirkschneider","album":"My Way","uri":"http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac","trackType":"flac"} Feb 17 11:48:05 volumio-10 volumio[1130]: verbose: CURRENT POSITION 2 Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState stateService play Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::syncState currentStatus play Feb 17 11:48:05 volumio-10 volumio[1130]: info: Received an update from plugin. extracting info from payload Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.212+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.213+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id=yandex_music/track/142933303:38224986@503646255:153863392 title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.214+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.214+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D88f5464834ff171dbd49bb5ced4e3277b2bb5701dc391b83403c84a76ba5a85f%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a1a6c9%2F0%2F4435855%2F89d02315.209325382.5.143823004%2Fflac-mp4&ext=.flac" title=Шаганэ Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.215+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.215+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.216+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.217+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.218+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.218+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.219+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.220+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.221+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.221+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.222+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=0 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.222+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 105ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 105ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 104ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: ------------------------------ 105ms Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::pushState Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioPushState Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.281+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" state=STATUS_PLAYING positionMs=282 volume=21 Feb 17 11:48:05 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:05.281+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-vlvbln-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D4071ad987c2068af919e7c895cf5c6c472e987cae824b0632427dcc09f491845%2Clid%3D1602%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69a18bed%2F0%2F9863668%2Fcf34c0c9.208280590.5.142933303%2Fflac-mp4&ext=.flac" title=Jealousy Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3853]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3855]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 sudo[3853]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 sudo[3858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3858]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3855]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3862]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 sudo[3858]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3862]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3864]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 volumio[1130]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 17 11:48:05 volumio-10 sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 sudo[3867]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 sudo[3873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3871]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 sudo[3873]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 sudo[3873]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 17 11:48:05 volumio-10 sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 sudo[3876]: pam_unix(sudo:session): session closed for user root Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CoreStateMachine::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: CorePlayQueue::getQueue Feb 17 11:48:05 volumio-10 volumio[1130]: info: peppymeterbasic Daemon Started Feb 17 11:48:05 volumio-10 volumio[1130]: info: peppymeterbasic Daemon Started Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 17 11:48:10 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 17 11:48:11 volumio-10 wpa_supplicant[866]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="ZLT P21_CAC50A" Feb 17 11:48:11 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:11 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:11 volumio-10 wpa_supplicant[866]: wlan0: Failed to initiate sched scan Feb 17 11:48:11 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:11.877+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:11 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:19 volumio-10 dhcpcd[904]: eth0: carrier lost Feb 17 11:48:19 volumio-10 dhcpcd[984]: eth0: carrier lost Feb 17 11:48:20 volumio-10 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Feb 17 11:48:20 volumio-10 avahi-daemon[615]: Withdrawing address record for 192.168.0.141 on eth0. Feb 17 11:48:20 volumio-10 dhcpcd[904]: eth0: deleting route to 192.168.0.0/24 Feb 17 11:48:20 volumio-10 dhcpcd[904]: eth0: deleting default route via 192.168.0.1 Feb 17 11:48:20 volumio-10 avahi-daemon[615]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.141. Feb 17 11:48:20 volumio-10 avahi-daemon[615]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 17 11:48:20 volumio-10 volumio[1130]: info: Discovery: A device disappeared from network Feb 17 11:48:20 volumio-10 dhcpcd[984]: eth0: deleting route to 192.168.0.0/24 Feb 17 11:48:20 volumio-10 dhcpcd[984]: eth0: deleting default route via 192.168.0.1 Feb 17 11:48:20 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 11:48:20 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:20 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:20 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:20 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:20 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:20 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:20 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100c fail, reason -52 Feb 17 11:48:20 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100d fail, reason -52 Feb 17 11:48:20 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Feb 17 11:48:21 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Feb 17 11:48:21 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Feb 17 11:48:21 volumio-10 kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Feb 17 11:48:22 volumio-10 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Feb 17 11:48:22 volumio-10 wpa_supplicant[866]: wlan0: Trying to associate with SSID 'ZLT P21_CAC50A' Feb 17 11:48:22 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:22.843+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 17 11:48:22 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:22.843+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 17 11:48:22 volumio-10 dhcpcd[904]: eth0: carrier acquired Feb 17 11:48:22 volumio-10 ntpd[762]: Deleting interface #3 eth0, 192.168.0.141#123, interface stats: received=180, sent=194, dropped=0, active_time=437 secs Feb 17 11:48:22 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:22 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:22 volumio-10 dhcpcd[984]: eth0: carrier acquired Feb 17 11:48:22 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:22 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:22 volumio-10 ntpd[762]: 45.141.102.99 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 45.90.217.6 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 92.241.12.152 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 89.109.251.21 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 151.0.2.54 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 90.188.9.144 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 89.109.251.24 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 162.159.200.123 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 151.0.2.53 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 51.250.53.172 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 51.250.68.198 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 92.255.126.3 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 185.209.85.222 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 91.188.214.68 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 ntpd[762]: 87.103.245.205 local addr 192.168.0.141 -> Feb 17 11:48:22 volumio-10 dhcpcd[984]: eth0: IAID dd:ad:bc:78 Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:22 volumio-10 dhcpcd[904]: eth0: IAID dd:ad:bc:78 Feb 17 11:48:22 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:22 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:22.859+09:00 level=INFO msg="enabling BLE discovery" Feb 17 11:48:22 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:22.860+09:00 level=INFO msg="enabling BLE discovery" Feb 17 11:48:22 volumio-10 bluetoothd[743]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/D8:3A:DD:AD:BC:7B/settings: (Failed to create file “/usr/var/lib/bluetooth/D8:3A:DD:AD:BC:7B/settings.NU19K3”: No such file or directory) Feb 17 11:48:23 volumio-10 dhcpcd[904]: eth0: soliciting an IPv6 router Feb 17 11:48:23 volumio-10 dhcpcd[984]: eth0: rebinding lease of 192.168.0.141 Feb 17 11:48:23 volumio-10 dhcpcd[984]: eth0: probing address 192.168.0.141/24 Feb 17 11:48:23 volumio-10 dhcpcd[904]: eth0: rebinding lease of 192.168.0.141 Feb 17 11:48:23 volumio-10 wpa_supplicant[866]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Feb 17 11:48:23 volumio-10 wpa_supplicant[866]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="ZLT P21_CAC50A" auth_failures=7 duration=135 reason=CONN_FAILED Feb 17 11:48:23 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:23.350+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 17 11:48:23 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:23 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:23 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:23 volumio-10 dhcpcd[904]: eth0: probing address 192.168.0.141/24 Feb 17 11:48:23 volumio-10 dhcpcd[984]: eth0: soliciting an IPv6 router Feb 17 11:48:25 volumio-10 volumio[1130]: info: Volumio Network Manager: Network status updated: 0 Feb 17 11:48:28 volumio-10 dhcpcd[904]: eth0: leased 192.168.0.141 for 86400 seconds Feb 17 11:48:28 volumio-10 dhcpcd[904]: eth0: adding route to 192.168.0.0/24 Feb 17 11:48:28 volumio-10 dhcpcd[904]: eth0: adding default route via 192.168.0.1 Feb 17 11:48:28 volumio-10 avahi-daemon[615]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.141. Feb 17 11:48:28 volumio-10 avahi-daemon[615]: New relevant interface eth0.IPv4 for mDNS. Feb 17 11:48:28 volumio-10 avahi-daemon[615]: Registering new address record for 192.168.0.141 on eth0.IPv4. Feb 17 11:48:28 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:28.670+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=true macAddress=d8:3a:dd:ad:bc:78 ip4Address=192.168.0.141/24 ip6Address= Feb 17 11:48:28 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:28 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:28 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:28 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:28.689+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.174:50574 Feb 17 11:48:28 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:28.751+09:00 level=INFO msg="disabling BLE discovery" Feb 17 11:48:28 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:28.754+09:00 level=INFO msg="connection closed, stopping read loop" component=conn/multi net=ble addr=D8:3A:DD:AD:BC:7B%00 Feb 17 11:48:28 volumio-10 dhcpcd[984]: eth0: leased 192.168.0.141 for 86400 seconds Feb 17 11:48:28 volumio-10 dhcpcd[984]: eth0: adding route to 192.168.0.0/24 Feb 17 11:48:28 volumio-10 dhcpcd[984]: eth0: adding default route via 192.168.0.1 Feb 17 11:48:29 volumio-10 volumio[1130]: info: Discovery: adding 3301d4e9-9876-416a-9e0d-baff6871c0fc Feb 17 11:48:29 volumio-10 volumio[1130]: info: Discovery: Found device Volumio 10 Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:29 volumio-10 volumio5-onboarding[1298]: time=2026-02-17T11:48:29.319+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.174:43808 @ 0x20b90e0" available=true connected=true macAddress=d8:3a:dd:ad:bc:78 ip4Address=192.168.0.141/24 ip6Address= Feb 17 11:48:29 volumio-10 volumio[1130]: info: Received Get System Info Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 11:48:29 volumio-10 volumio[1130]: info: Discovery: Getting this device information Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::volumioGetState Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 11:48:29 volumio-10 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 11:48:29 volumio-10 volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:48:29 volumio-10 volumio[1130]: Error: read ECONNRESET Feb 17 11:48:29 volumio-10 volumio[1130]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Feb 17 11:48:29 volumio-10 volumio[1130]: errno: -104, Feb 17 11:48:29 volumio-10 volumio[1130]: code: 'ECONNRESET', Feb 17 11:48:29 volumio-10 volumio[1130]: syscall: 'read' Feb 17 11:48:29 volumio-10 volumio[1130]: } Feb 17 11:48:29 volumio-10 volumio[1130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 11:48:30 volumio-10 sudo[4100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-17 11:47 Feb 17 11:48:30 volumio-10 sudo[4100]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"