-- Logs begin at Thu 2019-02-14 15:41:58 IST, end at Thu 2025-05-01 21:35:52 IST. -- May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 21:34:58 volumio volumio[878]: info: Discovery: Getting this device information May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 21:34:58 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.29.10:3000 from 192.168.29.147 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 21:34:58 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 21:35:02 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:35:03 volumio sudo[4042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 21:35:03 volumio sudo[4042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:03 volumio sudo[4042]: pam_unix(sudo:session): session closed for user root May 01 21:35:03 volumio sudo[4045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 21:35:03 volumio sudo[4045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:03 volumio sudo[4045]: pam_unix(sudo:session): session closed for user root May 01 21:35:04 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.29.10 from 192.168.29.147 UA: Mozilla/5.0 (Linux; Android 14; V2141 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.110 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 21:35:04 volumio sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 21:35:04 volumio sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:04 volumio sudo[4052]: pam_unix(sudo:session): session closed for user root May 01 21:35:04 volumio sudo[4055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 21:35:04 volumio sudo[4055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:04 volumio sudo[4055]: pam_unix(sudo:session): session closed for user root May 01 21:35:04 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.29.10 from 192.168.29.147 UA: Mozilla/5.0 (Linux; Android 14; V2141 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.110 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 21:35:04 volumio volumio[878]: info: Listing playlists May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 21:35:04 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 21:35:14 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources May 01 21:35:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:14 volumio volumio[878]: info: CoreCommandRouter::volumioGetQueue May 01 21:35:14 volumio volumio[878]: info: CoreStateMachine::getQueue May 01 21:35:14 volumio volumio[878]: info: CorePlayQueue::getQueue May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::volumioPause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::servicePause May 01 21:35:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::servicePause May 01 21:35:17 volumio volumio[878]: info: [1746115517529] ControllerWebradio::pause May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand pause May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand pause took 0 milliseconds May 01 21:35:17 volumio volumio[878]: info: May 01 21:35:17 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:17 volumio volumio[878]: info: May 01 21:35:17 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:17 volumio volumio[878]: info: May 01 21:35:17 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand status took 2 milliseconds May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand status took 1 milliseconds May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:17 volumio volumio[878]: info: May 01 21:35:17 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand status took 3 milliseconds May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand status took 1 milliseconds May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:17 volumio volumio[878]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":334074,"duration":628,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/9791457/episode/latest/shoutcast.mp3","trackType":"mp3"} May 01 21:35:17 volumio volumio[878]: verbose: CURRENT POSITION 0 May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState stateService pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:17 volumio volumio[878]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":334074,"duration":628,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/9791457/episode/latest/shoutcast.mp3","trackType":"mp3"} May 01 21:35:17 volumio volumio[878]: verbose: CURRENT POSITION 0 May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState stateService pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 01 21:35:17 volumio volumio[878]: info: ------------------------------ 19ms May 01 21:35:17 volumio volumio[878]: info: ------------------------------ 19ms May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand playlistinfo took 16 milliseconds May 01 21:35:17 volumio volumio[878]: info: sendMpdCommand playlistinfo took 15 milliseconds May 01 21:35:17 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:17 volumio volumio[878]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":334074,"duration":628,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/9791457/episode/latest/shoutcast.mp3","trackType":"mp3"} May 01 21:35:17 volumio volumio[878]: verbose: CURRENT POSITION 0 May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState stateService pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 01 21:35:17 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:17 volumio volumio[878]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":334074,"duration":628,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"shoutcast.mp3","artist":null,"album":null,"uri":"http://api.spreaker.com/listen/user/9791457/episode/latest/shoutcast.mp3","trackType":"mp3"} May 01 21:35:17 volumio volumio[878]: verbose: CURRENT POSITION 0 May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState stateService pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus pause May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::pushState May 01 21:35:17 volumio volumio[878]: info: CoreCommandRouter::volumioPushState May 01 21:35:17 volumio volumio[878]: info: CoreStateMachine::stPlaybackTimer May 01 21:35:17 volumio volumio[878]: info: ------------------------------ 33ms May 01 21:35:17 volumio volumio[878]: info: ------------------------------ 32ms May 01 21:35:17 volumio volumio[878]: info: PeppyMeterBasic ---peppymeterbasic status pause May 01 21:35:17 volumio volumio[878]: info: PeppyMeterBasic ---peppymeterbasic status pause May 01 21:35:17 volumio sudo[4146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 01 21:35:17 volumio sudo[4146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:17 volumio sudo[4146]: pam_unix(sudo:session): session closed for user root May 01 21:35:17 volumio volumio[878]: info: PeppyMeterBasic ---peppymeterbasic status pause May 01 21:35:17 volumio sudo[4149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 01 21:35:17 volumio sudo[4149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:17 volumio volumio[878]: info: PeppyMeterBasic ---peppymeterbasic status pause May 01 21:35:17 volumio sudo[4149]: pam_unix(sudo:session): session closed for user root May 01 21:35:17 volumio sudo[4152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 01 21:35:17 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:17 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root May 01 21:35:17 volumio volumio[878]: info: peppymeterbasic Daemon Stop May 01 21:35:17 volumio volumio[878]: info: peppymeterbasic Daemon Stop May 01 21:35:17 volumio volumio[878]: info: peppymeterbasic Daemon Stop May 01 21:35:17 volumio sudo[4155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 01 21:35:17 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:17 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root May 01 21:35:17 volumio volumio[878]: info: peppymeterbasic Daemon Stop May 01 21:35:20 volumio volumio[878]: info: CoreCommandRouter::volumioPlay May 01 21:35:20 volumio volumio[878]: info: CoreStateMachine::play index undefined May 01 21:35:20 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:35:20 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:20 volumio volumio[878]: info: CoreStateMachine::startPlaybackTimer May 01 21:35:20 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:20 volumio volumio[878]: info: [1746115520636] ControllerWebradio::resume May 01 21:35:20 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand play May 01 21:35:20 volumio volumio[878]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 01 21:35:20 volumio volumio[878]: info: FusionDsp - ---- read samplerate from file: 44100 May 01 21:35:20 volumio volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 21:35:20 volumio volumio[878]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 01 21:35:20 volumio volumio[878]: info: FusionDsp - ---- read samplerate from file: 44100 May 01 21:35:20 volumio volumio[878]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 01 21:35:21 volumio volumio[878]: info: May 01 21:35:21 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand play took 1151 milliseconds May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:21 volumio volumio[878]: info: May 01 21:35:21 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:21 volumio volumio[878]: info: May 01 21:35:21 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:21 volumio volumio[878]: info: May 01 21:35:21 volumio volumio[878]: ---------------------------- MPD announces state update: player May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::getState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand status took 3 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand status took 3 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand status took 3 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand status took 2 milliseconds May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseState May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 21:35:21 volumio volumio[878]: error: FusionDsp - WebSocket error: [object Object] May 01 21:35:21 volumio volumio[878]: error: FusionDsp - WebSocket error: [object Object] May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand playlistinfo took 2 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand playlistinfo took 3 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand playlistinfo took 3 milliseconds May 01 21:35:21 volumio volumio[878]: info: sendMpdCommand playlistinfo took 3 milliseconds May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:21 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:21 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:21 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:21 volumio volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:21 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:21 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:21 volumio volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:21 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:21 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:21 volumio volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:35:21 volumio volumio[878]: info: ControllerMpd::pushState May 01 21:35:21 volumio volumio[878]: info: CoreCommandRouter::servicePushState May 01 21:35:21 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:21 volumio volumio[878]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 01 21:35:21 volumio volumio[878]: info: ------------------------------ 10ms May 01 21:35:21 volumio volumio[878]: info: ------------------------------ 9ms May 01 21:35:21 volumio volumio[878]: info: ------------------------------ 8ms May 01 21:35:21 volumio volumio[878]: info: ------------------------------ 8ms May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 21:35:23 volumio volumio[878]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 21:35:23 volumio volumio[878]: info: Received Get System Version May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 21:35:23 volumio volumio[878]: info: Received Get System Info May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 21:35:23 volumio volumio[878]: info: Discovery: Getting this device information May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:35:23 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 21:35:28 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 21:35:28 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 21:35:28 volumio volumio[878]: info: Discovery: Getting this device information May 01 21:35:28 volumio volumio[878]: info: CoreCommandRouter::volumioGetState May 01 21:35:28 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:28 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 21:35:38 volumio volumio[878]: info: CoreCommandRouter::volumioPlay May 01 21:35:38 volumio volumio[878]: info: CoreStateMachine::play index undefined May 01 21:35:38 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 21:35:38 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:38 volumio volumio[878]: info: CoreStateMachine::startPlaybackTimer May 01 21:35:38 volumio volumio[878]: info: CorePlayQueue::getTrack 0 May 01 21:35:38 volumio volumio[878]: info: [1746115538620] ControllerWebradio::resume May 01 21:35:38 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand play May 01 21:35:38 volumio volumio[878]: info: sendMpdCommand play took 0 milliseconds May 01 21:35:42 volumio volumio[878]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 May 01 21:35:42 volumio volumio[878]: info: FusionDsp - Stopping FusionDsp service May 01 21:35:42 volumio volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:35:42 volumio volumio[878]: TypeError: Cannot read property 'stop' of null May 01 21:35:42 volumio volumio[878]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 01 21:35:42 volumio volumio[878]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 01 21:35:42 volumio volumio[878]: at /volumio/app/pluginmanager.js:956:12 May 01 21:35:42 volumio volumio[878]: at ChildProcess.exithandler (child_process.js:374:7) May 01 21:35:42 volumio volumio[878]: at ChildProcess.emit (events.js:400:28) May 01 21:35:42 volumio volumio[878]: at maybeClose (internal/child_process.js:1088:16) May 01 21:35:42 volumio volumio[878]: at Process.ChildProcess._handle.onexit (internal/child_process.js:296:5) May 01 21:35:42 volumio volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:35:42 volumio sudo[4258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 21:34 May 01 21:35:42 volumio sudo[4258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:42 volumio sudo[4258]: pam_unix(sudo:session): session closed for user root May 01 21:35:42 volumio volumio-remote-updater[583]: [2025-05-01 21:35:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 01 21:35:42 volumio volumio-remote-updater[583]: [2025-05-01 21:35:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 01 21:35:42 volumio go-librespot[1281]: time="2025-05-01T21:35:42+05:30" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 01 21:35:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 01 21:35:42 volumio sudo[3354]: pam_unix(sudo:session): session closed for user root May 01 21:35:42 volumio ntfs-3g[1082]: Unmounting /dev/sdb1 (RAJESH) May 01 21:35:43 volumio systemd[1]: media-RAJESH.mount: Succeeded. May 01 21:35:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 01 21:35:43 volumio systemd[1]: Started dynamicswap service. May 01 21:35:43 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 21:35:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 01 21:35:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 01 21:35:43 volumio systemd[1]: Stopped Volumio Backend Module. May 01 21:35:43 volumio systemd[1]: Started Volumio Backend Module. May 01 21:35:43 volumio systemd[1]: Started dynamicswap service. May 01 21:35:43 volumio systemd[1]: dynamicswap.service: Succeeded. May 01 21:35:43 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:43 volumio volumio[4275]: info: ----- Volumio3 ---- May 01 21:35:43 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:43 volumio volumio[4275]: info: ----- System startup ---- May 01 21:35:43 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:43 volumio volumio[4275]: info: MYVOLUMIO Environment detected May 01 21:35:43 volumio volumio[4275]: info: Plugin folders cleanup May 01 21:35:43 volumio volumio[4275]: info: Scanning into folder /volumio/app/plugins/ May 01 21:35:43 volumio volumio[4275]: info: Scanning category audio_interface May 01 21:35:43 volumio volumio[4275]: info: Scanning category miscellanea May 01 21:35:43 volumio volumio[4275]: info: Scanning category music_service May 01 21:35:43 volumio volumio[4275]: info: Scanning category plugins.json May 01 21:35:43 volumio volumio[4275]: info: Scanning category system_controller May 01 21:35:43 volumio volumio[4275]: info: Scanning category user_interface May 01 21:35:43 volumio volumio[4275]: info: Scanning into folder /data/plugins/ May 01 21:35:43 volumio volumio[4275]: info: Scanning category audio_interface May 01 21:35:43 volumio volumio[4275]: info: Scanning category music_service May 01 21:35:43 volumio volumio[4275]: info: Scanning category user_interface May 01 21:35:43 volumio volumio[4275]: info: Plugin folders cleanup completed May 01 21:35:43 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:43 volumio volumio[4275]: info: ----- Core plugins startup ---- May 01 21:35:43 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:43 volumio volumio[4275]: info: Loading plugins from folder /volumio/app/plugins/ May 01 21:35:43 volumio volumio[4275]: info: Adding plugin upnp to MyMusic Plugins May 01 21:35:43 volumio volumio[4275]: info: Adding plugin airplay_emulation to MyMusic Plugins May 01 21:35:43 volumio volumio[4275]: info: Adding plugin upnp_browser to MyMusic Plugins May 01 21:35:43 volumio volumio[4275]: info: Loading plugins from folder /data/plugins/ May 01 21:35:43 volumio volumio[4275]: info: Loading plugin "system"... May 01 21:35:43 volumio volumio[4275]: info: Loading plugin "appearance"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "network"... May 01 21:35:44 volumio volumio[4275]: info: Refreshing Cached IP Addresses May 01 21:35:44 volumio sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 21:35:44 volumio sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:44 volumio sudo[4302]: pam_unix(sudo:session): session closed for user root May 01 21:35:44 volumio sudo[4305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 21:35:44 volumio sudo[4305]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "services"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "alsa_controller"... May 01 21:35:44 volumio sudo[4305]: pam_unix(sudo:session): session closed for user root May 01 21:35:44 volumio sudo[4319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 01 21:35:44 volumio sudo[4319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:44 volumio sudo[4319]: pam_unix(sudo:session): session closed for user root May 01 21:35:44 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "wizard"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "networkfs"... May 01 21:35:44 volumio volumio[4275]: info: Starting Udev Watcher for removable devices May 01 21:35:44 volumio volumio[4275]: info: Ignoring mount for partition: boot May 01 21:35:44 volumio volumio[4275]: info: Ignoring mount for partition: volumio May 01 21:35:44 volumio volumio[4275]: info: Ignoring mount for partition: volumio_data May 01 21:35:44 volumio volumio[4275]: info: Mounting Device RAJESH May 01 21:35:44 volumio sudo[4337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/RAJESH -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 01 21:35:44 volumio sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:44 volumio ntfs-3g[4340]: Version 2017.3.23AR.3 integrated FUSE 28 May 01 21:35:44 volumio ntfs-3g[4340]: Mounted /dev/sdb1 (Read-Write, label "RAJESH", NTFS 3.1) May 01 21:35:44 volumio ntfs-3g[4340]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 01 21:35:44 volumio ntfs-3g[4340]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 May 01 21:35:44 volumio ntfs-3g[4340]: Global ownership and permissions enforced, configuration type 7 May 01 21:35:44 volumio sudo[4337]: pam_unix(sudo:session): session closed for user root May 01 21:35:44 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "volumio_command_line_client"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "upnp"... May 01 21:35:44 volumio volumio[4275]: info: [1746115544592] Starting Upmpd Daemon May 01 21:35:44 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "my_music"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "mpd"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "upnp_browser"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "alarm-clock"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "airplay_emulation"... May 01 21:35:44 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "last_100"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "webradio"... May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "i2s_dacs"... May 01 21:35:44 volumio volumio[4275]: info: I2S DAC not set, start Auto-detection May 01 21:35:44 volumio volumio[4275]: info: Loading plugin "volumiodiscovery"... May 01 21:35:44 volumio volumio[4275]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 21:35:44 volumio volumio[4275]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 21:35:44 volumio volumio[4275]: *** WARNING *** For more information see May 01 21:35:44 volumio volumio[4275]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 21:35:44 volumio volumio[4275]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 21:35:44 volumio volumio[4275]: *** WARNING *** For more information see May 01 21:35:44 volumio node[4275]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 01 21:35:44 volumio node[4275]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 21:35:44 volumio node[4275]: *** WARNING *** For more information see May 01 21:35:44 volumio node[4275]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 01 21:35:44 volumio node[4275]: *** WARNING *** Please fix your application to use the native API of Avahi! May 01 21:35:44 volumio node[4275]: *** WARNING *** For more information see May 01 21:35:44 volumio volumio[4275]: info: Applying required configuration parameters for plugin volumiodiscovery May 01 21:35:46 volumio volumio[4275]: info: Discovery: Started advertising with name: Volumio May 01 21:35:46 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 01 21:35:46 volumio volumio[4275]: info: Loading plugin "soundcloud"... May 01 21:35:46 volumio volumio[4275]: info: Loading plugin "spop"... May 01 21:35:46 volumio volumio[4275]: info: Loading plugin "youtube2"... May 01 21:35:47 volumio volumio[4275]: info: Loading plugin "ytmusic"... May 01 21:35:47 volumio volumio-remote-updater[583]: [2025-05-01 21:35:47] [connect] Successful connection May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "outputs"... May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "albumart"... May 01 21:35:48 volumio volumio[4275]: info: Plugin example_plugin is not enabled May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "inputs"... May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "updater_comm"... May 01 21:35:48 volumio volumio[4275]: info: Plugin mpdemulation is not enabled May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "rest_api"... May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "websocket"... May 01 21:35:48 volumio volumio[4275]: info: Starting Socket.io Server version 2.3.0 May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "fusiondsp"... May 01 21:35:48 volumio volumio[4275]: Forking 1 albumart workers May 01 21:35:48 volumio volumio[4275]: info: Applying required configuration parameters for plugin fusiondsp May 01 21:35:48 volumio volumio[4275]: info: Loading plugin "motherearthradio"... May 01 21:35:49 volumio volumio[4275]: info: Applying required configuration parameters for plugin motherearthradio May 01 21:35:49 volumio volumio[4275]: info: [1746115549053] [MotherEarth] API delay: 5 May 01 21:35:49 volumio volumio[4275]: info: Loading plugin "radio_paradise"... May 01 21:35:49 volumio volumio[4275]: Starting albumart workers May 01 21:35:49 volumio volumio[4275]: info: Applying required configuration parameters for plugin radio_paradise May 01 21:35:49 volumio volumio[4275]: info: [1746115549137] [RadioParadise] API delay: 5 May 01 21:35:49 volumio volumio[4275]: info: Loading plugin "peppymeterbasic"... May 01 21:35:49 volumio volumio[4275]: info: Loading i18n strings for locale en May 01 21:35:49 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 01 21:35:49 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 01 21:35:49 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 01 21:35:49 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 01 21:35:49 volumio volumio[4275]: Updating browse sources language May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::initPlayerControls May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 21:35:49 volumio volumio[4275]: Express server listening on port 3000 May 01 21:35:49 volumio volumio[4275]: [Metrics] WebUI: 5s 871.50ms May 01 21:35:49 volumio volumio[4275]: info: Setting Device type: x86 May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::resetVolumioState May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::getcurrentVolume May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:49 volumio volumio[4275]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 01 21:35:49 volumio volumio[4275]: info: Completed loading Core Plugins May 01 21:35:49 volumio volumio[4275]: info: Preparing to generate the ALSA configuration file May 01 21:35:49 volumio volumio[4275]: info: Volumio Network Manager: Network status updated: 1 May 01 21:35:49 volumio volumio[4275]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 01 21:35:49 volumio volumio[4275]: wlan0 Interface doesn't support scanning. May 01 21:35:49 volumio volumio[4275]: info: Cannot use regular scanning, forcing with ap-force May 01 21:35:49 volumio sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 01 21:35:49 volumio sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio sudo[4367]: pam_unix(sudo:session): session closed for user root May 01 21:35:49 volumio volumio[4275]: command failed: No such device (-19) May 01 21:35:49 volumio volumio[4275]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 01 21:35:49 volumio volumio[4275]: command failed: No such device (-19) May 01 21:35:49 volumio volumio[4275]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 01 21:35:49 volumio volumio[4275]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 21:35:49 volumio volumio[4275]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 21:35:49 volumio volumio[4275]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 01 21:35:49 volumio volumio[4275]: info: Reading ALSA contributions from plugins. May 01 21:35:49 volumio volumio[4275]: verbose: New Socket.io Connection to 192.168.29.10 from 192.168.29.147 UA: Mozilla/5.0 (Linux; Android 14; V2141 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.110 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 01 21:35:49 volumio volumio[4275]: verbose: New Socket.io Connection to 192.168.29.10 from 192.168.29.147 UA: Mozilla/5.0 (Linux; Android 14; V2141 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.110 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 01 21:35:49 volumio volumio-remote-updater[583]: [2025-05-01 21:35:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746115547 101 May 01 21:35:49 volumio volumio[4275]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 01 21:35:49 volumio volumio[4275]: Unable to parse: May 01 21:35:49 volumio volumio[4275]: Simple mixer control 'Power LED',0 May 01 21:35:49 volumio volumio[4275]: Capabilities: pswitch pswitch-joined May 01 21:35:49 volumio volumio[4275]: Playback channels: Mono May 01 21:35:49 volumio volumio[4275]: Mono: Playback [on] May 01 21:35:49 volumio volumio[4275]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 21:35:49 volumio volumio[4275]: info: Discovery: Getting this device information May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioGetState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 21:35:49 volumio volumio[4275]: info: Reloading queue from file May 01 21:35:49 volumio volumio[4275]: Unable to parse: May 01 21:35:49 volumio volumio[4275]: Simple mixer control 'Power LED',0 May 01 21:35:49 volumio volumio[4275]: Capabilities: pswitch pswitch-joined May 01 21:35:49 volumio volumio[4275]: Playback channels: Mono May 01 21:35:49 volumio volumio[4275]: Mono: Playback [on] May 01 21:35:49 volumio volumio[4275]: info: VolumeController:: Volume=undefined Mute =false May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::pushState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioPushState May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::updateTrackBlock May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrackBlock May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioRetrievevolume May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::setRepeat true single undefined May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::pushState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioPushState May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::setRandom null May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::pushState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioPushState May 01 21:35:49 volumio volumio[4275]: verbose: New Socket.io Connection to 192.168.29.10:3000 from 192.168.29.147 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 May 01 21:35:49 volumio volumio[4275]: Unable to parse: May 01 21:35:49 volumio volumio[4275]: Simple mixer control 'Power LED',0 May 01 21:35:49 volumio volumio[4275]: Capabilities: pswitch pswitch-joined May 01 21:35:49 volumio volumio[4275]: Playback channels: Mono May 01 21:35:49 volumio volumio[4275]: Mono: Playback [on] May 01 21:35:49 volumio volumio[4275]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioGetState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 21:35:49 volumio volumio[4275]: Unable to parse: May 01 21:35:49 volumio volumio[4275]: Simple mixer control 'Power LED',0 May 01 21:35:49 volumio volumio[4275]: Capabilities: pswitch pswitch-joined May 01 21:35:49 volumio volumio[4275]: Playback channels: Mono May 01 21:35:49 volumio volumio[4275]: Mono: Playback [on] May 01 21:35:49 volumio volumio[4275]: info: VolumeController:: Volume=undefined Mute =false May 01 21:35:49 volumio volumio[4275]: info: CoreStateMachine::pushState May 01 21:35:49 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioPushState May 01 21:35:49 volumio volumio[4275]: info: Asound.conf file unchanged, so no further update is needed May 01 21:35:49 volumio volumio[4275]: info: Output device has changed, restarting MPD May 01 21:35:49 volumio sudo[4380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 21:35:49 volumio sudo[4380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio sudo[4380]: pam_unix(sudo:session): session closed for user root May 01 21:35:49 volumio volumio[4275]: info: Output device has changed, restarting Shairport Sync May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:49 volumio sudo[4383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 21:35:49 volumio sudo[4383]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio systemd[1]: Stopping Music Player Daemon... May 01 21:35:49 volumio volumio[4275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 21:35:49 volumio volumio[4275]: info: ___________ START PLUGINS ___________ May 01 21:35:49 volumio volumio[4275]: info: ControllerMpd::onStart: Initializing MPD May 01 21:35:49 volumio volumio[4275]: info: Creating MPD Configuration file May 01 21:35:49 volumio systemd[1]: mpd.service: Succeeded. May 01 21:35:49 volumio systemd[1]: Stopped Music Player Daemon. May 01 21:35:49 volumio systemd[1]: Starting Music Player Daemon... May 01 21:35:49 volumio sudo[4390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 21:35:49 volumio sudo[4390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:49 volumio volumio[4275]: info: [1746115549860] CoreMusicLibrary::Adding element Media Servers May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:49 volumio sudo[4390]: pam_unix(sudo:session): session closed for user root May 01 21:35:49 volumio sudo[4393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 21:35:49 volumio sudo[4393]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:49 volumio sudo[4388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 21:35:49 volumio sudo[4388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio sudo[4388]: pam_unix(sudo:session): session closed for user root May 01 21:35:49 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 01 21:35:49 volumio systemd[1]: mpd.service: Succeeded. May 01 21:35:49 volumio systemd[1]: Stopped Music Player Daemon. May 01 21:35:49 volumio volumio[4275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:49 volumio systemd[1]: Starting Music Player Daemon... May 01 21:35:49 volumio volumio[4275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:49 volumio volumio[4275]: info: [1746115549954] CoreMusicLibrary::Adding element Last_100 May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:49 volumio volumio[4275]: info: [1746115549956] CoreMusicLibrary::Adding element Webradio May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:49 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 21:35:49 volumio volumio[4275]: info: Initializing BBC Radios May 01 21:35:49 volumio sudo[4399]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 21:35:49 volumio sudo[4399]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:49 volumio sudo[4399]: pam_unix(sudo:session): session closed for user root May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:50 volumio volumio[4275]: info: [1746115550028] CoreMusicLibrary::Adding element SoundCloud May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source SoundCloud May 01 21:35:50 volumio volumio[4275]: info: Creating Spotify config file May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:50 volumio volumio[4275]: info: [1746115550040] CoreMusicLibrary::Adding element YouTube2 May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source SoundCloud May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube2 May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:50 volumio volumio[4275]: info: [1746115550044] CoreMusicLibrary::Adding element YouTube Music May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source SoundCloud May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube2 May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube Music May 01 21:35:50 volumio volumio[4275]: info: Loading i18n strings for locale en May 01 21:35:50 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 01 21:35:50 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 01 21:35:50 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 01 21:35:50 volumio volumio[4275]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: FusionDsp - mixtype--------------------- Hardware May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:50 volumio volumio[4275]: info: [1746115550058] CoreMusicLibrary::Adding element Mother Earth Radio May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source SoundCloud May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube2 May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube Music May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source Mother Earth Radio May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 01 21:35:50 volumio volumio[4275]: info: [1746115550061] CoreMusicLibrary::Adding element Radio Paradise May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source SoundCloud May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube2 May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source YouTube Music May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source Mother Earth Radio May 01 21:35:50 volumio volumio[4275]: Cannot find translation for source Radio Paradise May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 01 21:35:50 volumio volumio[4275]: info: Volumio Calling Home May 01 21:35:50 volumio volumio[4275]: info: Preparing to generate the ALSA configuration file May 01 21:35:50 volumio volumio[4275]: info: Discovery: adding c2a1f714-91ca-4bb4-bd2d-e1e348d5041c May 01 21:35:50 volumio volumio[4275]: info: Discovery: Found device Volumio May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::volumioGetState May 01 21:35:50 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:50 volumio volumio[4275]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 21:35:50 volumio volumio[4275]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 21:35:50 volumio volumio[4275]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 01 21:35:50 volumio volumio[4275]: info: Reading ALSA contributions from plugins. May 01 21:35:50 volumio volumio[4275]: verbose: New Socket.io Connection to 192.168.29.10 from 192.168.29.147 UA: Mozilla/5.0 (Linux; Android 14; V2141 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.110 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 01 21:35:50 volumio volumio[4275]: info: MPD Permissions set May 01 21:35:50 volumio volumio[4275]: info: MPD Permissions set May 01 21:35:50 volumio volumio[4275]: info: Spotify config file written May 01 21:35:50 volumio volumio[4275]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 01 21:35:50 volumio volumio[4275]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio sudo[4419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 01 21:35:50 volumio sudo[4419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:50 volumio systemd[1]: Stopping go-librespot Daemon... May 01 21:35:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 01 21:35:50 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 01 21:35:50 volumio systemd[1]: Stopped go-librespot Daemon. May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 01 21:35:50 volumio systemd[1]: Started go-librespot Daemon. May 01 21:35:50 volumio sudo[4419]: pam_unix(sudo:session): session closed for user root May 01 21:35:50 volumio go-librespot[4425]: go-librespot daemon starting... May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=info msg="running go-librespot 0.2.0" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=debug msg="app state loaded" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=debug msg="stored credentials not found" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=info msg="api server listening on 127.0.0.1:9879" May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 01 21:35:50 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:50 volumio volumio[4275]: info: No need to fix Spotify hosts May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 01 21:35:50 volumio go-librespot[4425]: time="2025-05-01T21:35:50+05:30" level=info msg="zeroconf server listening on port 44699" May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::volumioGetState May 01 21:35:51 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:51 volumio volumio[4275]: info: Volumio called home May 01 21:35:51 volumio mpd[4406]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 01 21:35:51 volumio mpd[4406]: output: No 'audio_output' defined in config file May 01 21:35:51 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:51 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:51 volumio sudo[4441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:35:51 volumio sudo[4441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio mpd[4406]: output: Successfully detected a sndio audio device May 01 21:35:51 volumio mpd[4406]: zeroconf: No global port, disabling zeroconf May 01 21:35:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:35:51 volumio sudo[4383]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio systemd[1]: Started Music Player Daemon. May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:35:51 volumio sudo[4393]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:51 volumio sudo[4446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:35:51 volumio sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio sudo[4441]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:35:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio sudo[4446]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::volumioGetState May 01 21:35:51 volumio volumio[4275]: info: CorePlayQueue::getTrack 0 May 01 21:35:51 volumio sudo[4450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:35:51 volumio sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:35:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio sudo[4450]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 21:35:51 volumio volumio[4275]: info: Listing playlists May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::volumioGetVisibleSources May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::volumioGetQueue May 01 21:35:51 volumio volumio[4275]: info: CoreStateMachine::getQueue May 01 21:35:51 volumio volumio[4275]: info: CorePlayQueue::getQueue May 01 21:35:51 volumio volumio[4275]: info: Shairport-Sync Started May 01 21:35:51 volumio volumio[4275]: Error adding Membership: Error: addMembership EINVAL May 01 21:35:51 volumio volumio[4275]: info: Shairport-Sync Started May 01 21:35:51 volumio volumio[4275]: info: Completed starting Core Plugins May 01 21:35:51 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:51 volumio volumio[4275]: info: ----- MyVolumio plugins startup ---- May 01 21:35:51 volumio volumio[4275]: info: ------------------------------------------- May 01 21:35:51 volumio volumio[4275]: info: [MyVolumio PluginManager] Fetching plans data.... May 01 21:35:51 volumio volumio[4275]: info: Asound.conf file unchanged, so no further update is needed May 01 21:35:51 volumio volumio[4275]: info: Output device has changed, restarting MPD May 01 21:35:51 volumio sudo[4459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 21:35:51 volumio sudo[4459]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio sudo[4459]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio volumio[4275]: info: Output device has changed, restarting Shairport Sync May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:51 volumio sudo[4462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 21:35:51 volumio sudo[4462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio systemd[1]: Stopping Music Player Daemon... May 01 21:35:51 volumio systemd[1]: mpd.service: Succeeded. May 01 21:35:51 volumio systemd[1]: Stopped Music Player Daemon. May 01 21:35:51 volumio volumio[4275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 21:35:51 volumio volumio[4275]: info: Preparing to generate the ALSA configuration file May 01 21:35:51 volumio systemd[1]: Starting Music Player Daemon... May 01 21:35:51 volumio volumio[4275]: info: Shairport-Sync Started May 01 21:35:51 volumio volumio[4275]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 01 21:35:51 volumio volumio[4275]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 01 21:35:51 volumio volumio[4275]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 01 21:35:51 volumio volumio[4275]: info: Reading ALSA contributions from plugins. May 01 21:35:51 volumio volumio[4275]: error: MPD error: Error: write EPIPE May 01 21:35:51 volumio volumio[4275]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 01 21:35:51 volumio volumio[4275]: error: MPD error: The expression evaluated to a falsy value: May 01 21:35:51 volumio volumio[4275]: assert.ok(self.idling) May 01 21:35:51 volumio volumio[4275]: error: The expression evaluated to a falsy value: May 01 21:35:51 volumio volumio[4275]: assert.ok(self.idling) May 01 21:35:51 volumio volumio[4275]: error: MPD error: Error: write EPIPE May 01 21:35:51 volumio volumio[4275]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} May 01 21:35:51 volumio sudo[4467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 21:35:51 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio volumio[4275]: info: MPD Permissions set May 01 21:35:51 volumio volumio[4275]: info: MPD running with PID4406 May 01 21:35:51 volumio volumio[4275]: ,establishing connection May 01 21:35:51 volumio sudo[4467]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:51 volumio volumio[4275]: info: Asound.conf file unchanged, so no further update is needed May 01 21:35:51 volumio volumio[4275]: info: Output device has changed, restarting MPD May 01 21:35:51 volumio sudo[4479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 01 21:35:51 volumio sudo[4479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio sudo[4477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:35:51 volumio sudo[4477]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio sudo[4479]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:35:51 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:35:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio volumio[4275]: info: Output device has changed, restarting Shairport Sync May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:35:51 volumio sudo[4477]: pam_unix(sudo:session): session closed for user root May 01 21:35:51 volumio sudo[4484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 01 21:35:51 volumio sudo[4484]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 01 21:35:51 volumio systemd[1]: mpd.service: Succeeded. May 01 21:35:51 volumio systemd[1]: Stopped Music Player Daemon. May 01 21:35:51 volumio volumio[4275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 01 21:35:51 volumio systemd[1]: Starting Music Player Daemon... May 01 21:35:51 volumio volumio[4275]: info: MPD Permissions set May 01 21:35:51 volumio volumio[4275]: info: Shairport-Sync Started May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 01 21:35:51 volumio sudo[4491]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 01 21:35:51 volumio sudo[4491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:51 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:51 volumio sudo[4491]: pam_unix(sudo:session): session closed for user root May 01 21:35:52 volumio volumio[4275]: info: Starting Shairport Sync May 01 21:35:52 volumio sudo[4505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 01 21:35:52 volumio sudo[4505]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:52 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 01 21:35:52 volumio systemd[1]: shairport-sync.service: Succeeded. May 01 21:35:52 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 01 21:35:52 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 01 21:35:52 volumio sudo[4505]: pam_unix(sudo:session): session closed for user root May 01 21:35:52 volumio volumio[4275]: info: Shairport-Sync Started May 01 21:35:52 volumio volumio[4275]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 01 21:35:52 volumio volumio[4275]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 01 21:35:52 volumio volumio[4275]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 01 21:35:52 volumio volumio[4275]: info: camilladsp service started and running in background, instance 1 May 01 21:35:52 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 01 21:35:52 volumio volumio[4275]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 01 21:35:52 volumio volumio[4275]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 01 21:35:52 volumio volumio[4275]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 01 21:35:52 volumio volumio[4275]: info: FusionDsp loaded May 01 21:35:52 volumio volumio[4275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 21:35:52 volumio volumio[4275]: info: FusionDsp - Reporting Fusion DSP Enabled May 01 21:35:52 volumio sudo[4520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 01 21:35:52 volumio sudo[4520]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 21:35:52 volumio volumio[4275]: info: Adding Signal Path Element [object Object] May 01 21:35:52 volumio volumio[4275]: info: Adding fusiondspeq DSP Signal Path Element May 01 21:35:52 volumio volumio[4275]: info: FusionDsp - ---- installed callbackRead May 01 21:35:52 volumio volumio[4275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:35:52 volumio volumio[4275]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 01 21:35:52 volumio volumio[4275]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) May 01 21:35:52 volumio volumio[4275]: at onErrorNT (internal/child_process.js:472:16) May 01 21:35:52 volumio volumio[4275]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 01 21:35:52 volumio volumio[4275]: at runNextTicks (internal/process/task_queues.js:64:3) May 01 21:35:52 volumio volumio[4275]: at listOnTimeout (internal/timers.js:526:9) May 01 21:35:52 volumio volumio[4275]: at processTimers (internal/timers.js:500:7) { May 01 21:35:52 volumio volumio[4275]: errno: -2, May 01 21:35:52 volumio volumio[4275]: code: 'ENOENT', May 01 21:35:52 volumio volumio[4275]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 01 21:35:52 volumio volumio[4275]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 01 21:35:52 volumio volumio[4275]: spawnargs: [ May 01 21:35:52 volumio volumio[4275]: '-p', May 01 21:35:52 volumio volumio[4275]: 9876, May 01 21:35:52 volumio volumio[4275]: '-o', May 01 21:35:52 volumio volumio[4275]: '/tmp/camilladsp.log', May 01 21:35:52 volumio volumio[4275]: '-l', May 01 21:35:52 volumio volumio[4275]: 'warn', May 01 21:35:52 volumio volumio[4275]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 01 21:35:52 volumio volumio[4275]: ] May 01 21:35:52 volumio volumio[4275]: } May 01 21:35:52 volumio volumio[4275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 21:35:52 volumio systemd[1]: Started FusionDsp Daemon. May 01 21:35:52 volumio sudo[4520]: pam_unix(sudo:session): session closed for user root May 01 21:35:52 volumio volumio[4522]: Traceback (most recent call last): May 01 21:35:52 volumio volumio[4522]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 1, in May 01 21:35:52 volumio volumio[4522]: from aiohttp import web May 01 21:35:52 volumio volumio[4522]: ModuleNotFoundError: No module named 'aiohttp' May 01 21:35:52 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 01 21:35:52 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 01 21:35:52 volumio sudo[4532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-01 21:34 May 01 21:35:52 volumio sudo[4532]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"