-- Logs begin at Mon 2025-11-03 21:40:58 EET, end at Mon 2025-11-03 21:57:10 EET. -- Nov 03 21:56:01 volumio sudo[4768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 21:56:01 volumio sudo[4768]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:01 volumio sudo[4768]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:01 volumio sudo[4771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 21:56:01 volumio sudo[4771]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:01 volumio sudo[4771]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:01 volumio volumio[1194]: verbose: New Socket.io Connection to 192.168.8.196 from 192.168.8.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Nov 03 21:56:02 volumio sudo[4774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 21:56:02 volumio sudo[4774]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:02 volumio sudo[4774]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:02 volumio sudo[4777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 21:56:02 volumio sudo[4777]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:02 volumio sudo[4777]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:02 volumio volumio[1194]: verbose: New Socket.io Connection to 192.168.8.196 from 192.168.8.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 21:56:02 volumio volumio[1194]: info: Received Get System Info Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:02 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:02 volumio volumio[1194]: info: Listing playlists Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 21:56:02 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 03 21:56:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 21:56:04 volumio volumio[1194]: info: Received Get System Info Nov 03 21:56:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:04 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:04 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:05 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 21:56:05 volumio volumio[1194]: info: Received Get System Info Nov 03 21:56:05 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:05 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:05 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:05 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:05 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:06 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 03 21:56:12 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 03 21:56:12 volumio volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 03 21:56:12 volumio volumio[1194]: info: CoreStateMachine::getQueue Nov 03 21:56:12 volumio volumio[1194]: info: CorePlayQueue::getQueue Nov 03 21:56:13 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:13 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:13 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:13 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:13 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:14 volumio go-librespot[3262]: time="2025-11-03T21:56:14+02:00" level=trace msg="sent dealer ping" Nov 03 21:56:14 volumio go-librespot[3262]: time="2025-11-03T21:56:14+02:00" level=trace msg="received dealer pong" Nov 03 21:56:16 volumio volumio[1194]: info: Nov 03 21:56:16 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:16 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:16 volumio volumio[1194]: info: Nov 03 21:56:16 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:16 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:16 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:16 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:16 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:16 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:16 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:16 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:16 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:16 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:16 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:16 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:16 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47433,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"fi_bassoradio_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=brf","artist":null,"album":null,"uri":"http://streaming.radioplay.fi/fi_bassoradio_128.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933&stationCode=brf","trackType":""} Nov 03 21:56:16 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:16 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:16 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:16 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:16 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:16 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:16 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:16 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:16 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:16 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:16 volumio volumio[1194]: info: ------------------------------ 8ms Nov 03 21:56:16 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:16 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:16 volumio sudo[4796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:16 volumio sudo[4796]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:16 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:16 volumio sudo[4796]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:16 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:16 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:16 volumio sudo[4799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:16 volumio sudo[4799]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:16 volumio sudo[4799]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:16 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Nov 03 21:56:20 volumio volumio[1194]: info: Preload queue cleared Nov 03 21:56:20 volumio volumio[1194]: info: Preloading song: spotify:track:4fgLmTOX8eIDwcFA1Zgwra Nov 03 21:56:20 volumio volumio[1194]: info: Preloading song: spotify:track:4rch7q4kFz6vqaNzt8FBj5 Nov 03 21:56:20 volumio volumio[1194]: info: Preloading song: spotify:track:1y1iqCHLIIqdlD83TbUPS8 Nov 03 21:56:20 volumio volumio[1194]: info: Exploding uri spotify:track:4fgLmTOX8eIDwcFA1Zgwra in service spop Nov 03 21:56:20 volumio volumio[1194]: SPOTIFY: EXPLODING URI:spotify:track:4fgLmTOX8eIDwcFA1Zgwra Nov 03 21:56:20 volumio volumio[1194]: info: Exploding uri spotify:track:4rch7q4kFz6vqaNzt8FBj5 in service spop Nov 03 21:56:20 volumio volumio[1194]: SPOTIFY: EXPLODING URI:spotify:track:4rch7q4kFz6vqaNzt8FBj5 Nov 03 21:56:20 volumio volumio[1194]: info: Exploding uri spotify:track:1y1iqCHLIIqdlD83TbUPS8 in service spop Nov 03 21:56:20 volumio volumio[1194]: SPOTIFY: EXPLODING URI:spotify:track:1y1iqCHLIIqdlD83TbUPS8 Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 21:56:20 volumio volumio[1194]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 21:56:20 volumio volumio[1194]: info: Received Get System Version Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 21:56:20 volumio volumio[1194]: info: Received Get System Info Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:20 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:21 volumio volumio[1194]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4fgLmTOX8eIDwcFA1Zgwra","service":"spop","name":"Ai Että","artist":"Isac Elliot","album":"Ai Että","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b2739200c7afffe073b321f78040","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 21:56:21 volumio volumio[1194]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4rch7q4kFz6vqaNzt8FBj5","service":"spop","name":"Kui paljon","artist":"Turisti","album":"Magic City","type":"song","duration":114,"albumart":"https://i.scdn.co/image/ab67616d0000b2736634d805244bd60dec7f3084","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 21:56:22 volumio volumio[1194]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1y1iqCHLIIqdlD83TbUPS8","service":"spop","name":"7 syntiä (feat. AHTI)","artist":"ABREU","album":"Traagista mut maagista","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273c95eb3113e5a70ae0084a046","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 21:56:23 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 03 21:56:23 volumio volumio[1194]: info: Preload queue cleared Nov 03 21:56:27 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 03 21:56:27 volumio volumio[1194]: info: Preload queue cleared Nov 03 21:56:30 volumio volumio[1194]: info: Preload queue cleared Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::ClearQueue Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::stop Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::stPlaybackTimer Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::serviceStop Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::serviceStop Nov 03 21:56:30 volumio volumio[1194]: info: [1762199790331] ControllerWebradio::stop Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::clearPlayQueue Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::saveQueue Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::volumioPushQueue Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::addQueueItems Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::addQueueItems Nov 03 21:56:30 volumio volumio[1194]: info: Preload queue cleared Nov 03 21:56:30 volumio volumio[1194]: info: Adding Item to queue: https://icecast.live.yle.fi/radio/YleX/icecast.audio Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::volumioPushQueue Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::saveQueue Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::updateTrackBlock Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrackBlock Nov 03 21:56:30 volumio volumio[1194]: info: CoreCommandRouter::volumioPlay Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::play index 0 Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::stop Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::play index undefined Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:30 volumio volumio[1194]: info: CoreStateMachine::startPlaybackTimer Nov 03 21:56:30 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:30 volumio volumio[1194]: info: [1762199790337] ControllerWebradio::clearAddPlayTrack Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand stop Nov 03 21:56:30 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status stop Nov 03 21:56:30 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:30 volumio sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Nov 03 21:56:30 volumio sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:30 volumio systemd[1]: Stopping peppymeterbasic Daemon... Nov 03 21:56:30 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Nov 03 21:56:30 volumio volumio[1194]: info: Nov 03 21:56:30 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:30 volumio volumio[1194]: info: sendMpdCommand stop took 24 milliseconds Nov 03 21:56:30 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:30 volumio volumio[1194]: info: sendMpdCommand stop took 17 milliseconds Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand clear Nov 03 21:56:30 volumio systemd[1]: peppymeterbasic.service: Succeeded. Nov 03 21:56:30 volumio systemd[1]: Stopped peppymeterbasic Daemon. Nov 03 21:56:30 volumio volumio[1194]: info: Nov 03 21:56:30 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:30 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:30 volumio volumio[1194]: info: sendMpdCommand status took 2 milliseconds Nov 03 21:56:30 volumio volumio[1194]: info: sendMpdCommand clear took 2 milliseconds Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:30 volumio sudo[4833]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand load "https://icecast.live.yle.fi/radio/YleX/icecast.audio" Nov 03 21:56:30 volumio volumio[1194]: error: updateQueue error: null Nov 03 21:56:30 volumio volumio[1194]: info: ------------------------------ 2ms Nov 03 21:56:30 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 21:56:30 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:30 volumio volumio[1194]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 03 21:56:30 volumio volumio[1194]: info: ------------------------------ 5ms Nov 03 21:56:30 volumio volumio[1194]: info: peppymeterbasic Daemon Stop Nov 03 21:56:31 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand add "https://icecast.live.yle.fi/radio/YleX/icecast.audio" Nov 03 21:56:31 volumio volumio[1194]: info: Nov 03 21:56:31 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:31 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:31 volumio volumio[1194]: info: sendMpdCommand add "https://icecast.live.yle.fi/radio/YleX/icecast.audio" took 0 milliseconds Nov 03 21:56:31 volumio volumio[1194]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 03 21:56:31 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand play Nov 03 21:56:31 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:31 volumio volumio[1194]: info: sendMpdCommand play took 0 milliseconds Nov 03 21:56:34 volumio volumio[1194]: info: Nov 03 21:56:34 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:34 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:34 volumio volumio[1194]: info: Nov 03 21:56:34 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:34 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:34 volumio volumio[1194]: info: Nov 03 21:56:34 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:34 volumio volumio[1194]: info: sendMpdCommand status took 31 milliseconds Nov 03 21:56:34 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:34 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:34 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:34 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:34 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:34 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:34 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:34 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus stop Nov 03 21:56:34 volumio volumio[1194]: info: ------------------------------ 33ms Nov 03 21:56:34 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 21:56:34 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:34 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:34 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:34 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":606,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:34 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:34 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:34 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:34 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:34 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:34 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:34 volumio volumio[1194]: info: ------------------------------ 10ms Nov 03 21:56:34 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:34 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:34 volumio sudo[4839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:34 volumio sudo[4839]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:34 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:34 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:34 volumio sudo[4842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:34 volumio sudo[4842]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:34 volumio systemd[1]: Started peppymeterbasic Daemon. Nov 03 21:56:34 volumio sudo[4839]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:34 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:34 volumio sudo[4842]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:34 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:34 volumio volumio[4843]: pygame 1.9.4.post1 Nov 03 21:56:34 volumio volumio[4843]: Hello from the pygame community. https://www.pygame.org/contribute.html Nov 03 21:56:39 volumio volumio[1194]: info: Nov 03 21:56:39 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:39 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:39 volumio volumio[1194]: info: Nov 03 21:56:39 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:39 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:39 volumio volumio[1194]: info: ------------------------------ 2ms Nov 03 21:56:39 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:39 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:39 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5605,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:39 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:39 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:39 volumio volumio[1194]: info: ------------------------------ 8ms Nov 03 21:56:39 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:39 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:39 volumio sudo[4863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:39 volumio sudo[4863]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:39 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:39 volumio sudo[4863]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:39 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:39 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:39 volumio sudo[4866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:39 volumio sudo[4866]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:39 volumio sudo[4866]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:39 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:39 volumio volumio[1194]: info: Nov 03 21:56:39 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:39 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:39 volumio volumio[1194]: info: Nov 03 21:56:39 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:39 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:39 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:39 volumio volumio[1194]: info: sendMpdCommand status took 1 milliseconds Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:39 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:39 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:39 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6112,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:39 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:39 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:39 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:39 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:39 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:39 volumio volumio[1194]: info: ------------------------------ 6ms Nov 03 21:56:39 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:39 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:39 volumio sudo[4869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:39 volumio sudo[4869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:39 volumio sudo[4869]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:39 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:39 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Nov 03 21:56:39 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:39 volumio sudo[4872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:39 volumio sudo[4872]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:39 volumio sudo[4872]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:39 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:42 volumio volumio[1194]: info: VolumeController::SetAlsaVolume54 Nov 03 21:56:42 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:42 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:42 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:42 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:42 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:42 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:42 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:42 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Nov 03 21:56:42 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 50 Nov 03 21:56:42 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 54 Nov 03 21:56:42 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:42 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 54 Nov 03 21:56:42 volumio sudo[4879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:42 volumio sudo[4879]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:42 volumio sudo[4879]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:42 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:42 volumio volumio[1194]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/YleX/d4afac1c-4932-4cca-98b0-aca258cc6c10.jpg' Nov 03 21:56:42 volumio volumio[1194]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/YleX/d4afac1c-4932-4cca-98b0-aca258cc6c10.jpg' Nov 03 21:56:43 volumio volumio[1194]: info: VolumeController::SetAlsaVolume60 Nov 03 21:56:43 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:43 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:43 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:43 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:43 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:43 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:43 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:43 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 03 21:56:43 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 54 Nov 03 21:56:43 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 60 Nov 03 21:56:43 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:43 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 60 Nov 03 21:56:43 volumio sudo[4883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:43 volumio sudo[4883]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:43 volumio sudo[4883]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:43 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 21:56:44 volumio volumio[1194]: info: Discovery: Getting this device information Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 21:56:44 volumio volumio[1194]: info: VolumeController::SetAlsaVolume59 Nov 03 21:56:44 volumio volumio[1194]: info: VolumeController::SetAlsaVolume64 Nov 03 21:56:44 volumio volumio[1194]: info: VolumeController::SetAlsaVolume55 Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:44 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:44 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:44 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:44 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:44 volumio sudo[4889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:44 volumio sudo[4889]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:44 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:44 volumio sudo[4889]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:44 volumio sudo[4892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:44 volumio sudo[4892]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:44 volumio sudo[4892]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 60 Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 55 Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:44 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 55 Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:44 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:44 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:44 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:44 volumio sudo[4895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:44 volumio sudo[4895]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:44 volumio sudo[4895]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:44 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:44 volumio go-librespot[3262]: time="2025-11-03T21:56:44+02:00" level=trace msg="sent dealer ping" Nov 03 21:56:45 volumio volumio[1194]: info: Nov 03 21:56:45 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:45 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:45 volumio volumio[1194]: info: Nov 03 21:56:45 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:45 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:45 volumio volumio[1194]: info: ------------------------------ 2ms Nov 03 21:56:45 volumio volumio[1194]: info: sendMpdCommand status took 1 milliseconds Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:45 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:45 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11737,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:45 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:45 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:45 volumio volumio[1194]: info: ------------------------------ 6ms Nov 03 21:56:45 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:45 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:45 volumio sudo[4898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:45 volumio sudo[4898]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:45 volumio sudo[4898]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:45 volumio sudo[4901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:45 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:45 volumio sudo[4901]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:45 volumio sudo[4901]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:45 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:45 volumio volumio[1194]: info: Nov 03 21:56:45 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:45 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:45 volumio volumio[1194]: info: Nov 03 21:56:45 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:45 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:45 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:45 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:45 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 21:56:45 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:45 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12114,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:45 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:45 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:45 volumio volumio[1194]: info: ------------------------------ 7ms Nov 03 21:56:45 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:45 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:45 volumio sudo[4904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:45 volumio sudo[4904]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:45 volumio sudo[4904]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 03 21:56:45 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:45 volumio sudo[4907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:45 volumio sudo[4907]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:45 volumio sudo[4907]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:45 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: SETTING SPOTIFY VOLUME 55 Nov 03 21:56:45 volumio volumio[1194]: info: Sending Spotify command with payload to local API: /player/volume Nov 03 21:56:45 volumio go-librespot[3262]: time="2025-11-03T21:56:45+02:00" level=debug msg="update volume requested to 36044/65535" Nov 03 21:56:45 volumio volumio[1194]: info: VolumeController::SetAlsaVolume45 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:45 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:45 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:45 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 55 Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 45 Nov 03 21:56:45 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:45 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 45 Nov 03 21:56:45 volumio sudo[4911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:45 volumio sudo[4911]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:45 volumio sudo[4911]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:45 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:46 volumio volumio[1194]: info: VolumeController::SetAlsaVolume41 Nov 03 21:56:46 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:46 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:46 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:46 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:46 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:46 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:46 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:46 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Nov 03 21:56:46 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 45 Nov 03 21:56:46 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 41 Nov 03 21:56:46 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:46 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 41 Nov 03 21:56:46 volumio sudo[4915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:46 volumio sudo[4915]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:46 volumio sudo[4915]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:46 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:47 volumio go-librespot[3262]: time="2025-11-03T21:56:47+02:00" level=trace msg="received dealer pong" Nov 03 21:56:47 volumio volumio[1194]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Nov 03 21:56:47 volumio volumio[1194]: info: Sending Spotify command with payload to local API: /player/volume Nov 03 21:56:50 volumio go-librespot[3262]: time="2025-11-03T21:56:50+02:00" level=trace msg="received accesspoint ping" Nov 03 21:56:50 volumio volumio[1194]: info: VolumeController::SetAlsaVolume75 Nov 03 21:56:50 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:50 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:50 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:50 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:50 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:50 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:50 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:50 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 03 21:56:50 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 41 Nov 03 21:56:50 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 75 Nov 03 21:56:50 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:50 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 75 Nov 03 21:56:50 volumio sudo[4933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:50 volumio sudo[4933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:50 volumio sudo[4933]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:50 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:51 volumio volumio[1194]: info: Nov 03 21:56:51 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:51 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:51 volumio volumio[1194]: info: Nov 03 21:56:51 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:51 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:51 volumio volumio[1194]: info: Nov 03 21:56:51 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:51 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:51 volumio volumio[1194]: info: Nov 03 21:56:51 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:51 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:51 volumio volumio[1194]: info: ------------------------------ 2ms Nov 03 21:56:51 volumio volumio[1194]: info: sendMpdCommand status took 1 milliseconds Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:51 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:51 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:51 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:51 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:51 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:51 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:51 volumio volumio[1194]: info: ------------------------------ 9ms Nov 03 21:56:51 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 03 21:56:51 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:51 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18238,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:51 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:51 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:51 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:51 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:51 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:51 volumio volumio[1194]: info: ------------------------------ 14ms Nov 03 21:56:51 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:51 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:51 volumio sudo[4936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:51 volumio sudo[4936]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:51 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:51 volumio sudo[4936]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:51 volumio sudo[4939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:51 volumio sudo[4939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:51 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:51 volumio sudo[4939]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:51 volumio sudo[4942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:51 volumio sudo[4942]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:51 volumio sudo[4942]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:51 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 03 21:56:51 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 03 21:56:51 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 03 21:56:51 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Nov 03 21:56:51 volumio sudo[4945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:51 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:51 volumio sudo[4945]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:51 volumio sudo[4945]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:51 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:52 volumio volumio[1194]: info: VolumeController::SetAlsaVolume65 Nov 03 21:56:52 volumio volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 21:56:52 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:52 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:52 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:52 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:52 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:52 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:52 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:56:52 volumio volumio[1194]: SPOTIFY: SPOTIFY VOLUME 75 Nov 03 21:56:52 volumio volumio[1194]: SPOTIFY: VOLUMIO VOLUME 65 Nov 03 21:56:52 volumio volumio[1194]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 03 21:56:52 volumio volumio[1194]: info: Setting Spotify Volume from Volumio: 65 Nov 03 21:56:52 volumio sudo[4949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:52 volumio sudo[4949]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:52 volumio sudo[4949]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:52 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:53 volumio volumio[1194]: SPOTIFY: SETTING SPOTIFY VOLUME 65 Nov 03 21:56:53 volumio volumio[1194]: info: Sending Spotify command with payload to local API: /player/volume Nov 03 21:56:57 volumio volumio[1194]: info: Nov 03 21:56:57 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:56:57 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:56:57 volumio volumio[1194]: info: Nov 03 21:56:57 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:56:57 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:56:57 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:56:57 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:56:57 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:56:57 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:56:57 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:56:57 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 21:56:57 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:56:57 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:56:57 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:56:57 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:57 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23613,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:56:57 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:56:57 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:56:57 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:56:57 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:56:57 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:56:57 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:57 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:57 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:56:57 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:57 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:56:57 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:56:57 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:56:57 volumio volumio[1194]: info: ------------------------------ 8ms Nov 03 21:56:57 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:57 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:56:57 volumio sudo[4967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:57 volumio sudo[4967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:57 volumio sudo[4967]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:57 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:56:57 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:56:57 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:56:57 volumio sudo[4970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:56:57 volumio sudo[4970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:56:57 volumio sudo[4970]: pam_unix(sudo:session): session closed for user root Nov 03 21:56:57 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:57:01 volumio volumio[1194]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/YleX/0a8f729c-880f-40dd-a577-3e21ed530b18.jpg' Nov 03 21:57:03 volumio volumio[1194]: info: Nov 03 21:57:03 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:57:03 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:57:03 volumio volumio[1194]: info: Nov 03 21:57:03 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:57:03 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:57:03 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:57:03 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:57:03 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:57:03 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:57:03 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:57:03 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 03 21:57:03 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:57:03 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:57:03 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:57:03 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:03 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29732,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:57:03 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:57:03 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:57:03 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:57:03 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:57:03 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:57:03 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:57:03 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:03 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:57:03 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:57:03 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:57:03 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:03 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:57:03 volumio volumio[1194]: info: ------------------------------ 8ms Nov 03 21:57:03 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:57:03 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:57:03 volumio sudo[5023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:57:03 volumio sudo[5023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:57:03 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:57:03 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:57:03 volumio sudo[5023]: pam_unix(sudo:session): session closed for user root Nov 03 21:57:03 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:57:03 volumio sudo[5026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:57:03 volumio sudo[5026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:57:03 volumio sudo[5026]: pam_unix(sudo:session): session closed for user root Nov 03 21:57:03 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:57:04 volumio volumio[1194]: info: Nov 03 21:57:04 volumio volumio[1194]: ---------------------------- MPD announces system playlist update Nov 03 21:57:04 volumio volumio[1194]: info: Ignoring MPD Status Update Nov 03 21:57:04 volumio volumio[1194]: info: Nov 03 21:57:04 volumio volumio[1194]: ---------------------------- MPD announces state update: player Nov 03 21:57:04 volumio volumio[1194]: info: ControllerMpd::getState Nov 03 21:57:04 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand status Nov 03 21:57:04 volumio volumio[1194]: info: ------------------------------ 1ms Nov 03 21:57:04 volumio volumio[1194]: info: sendMpdCommand status took 0 milliseconds Nov 03 21:57:04 volumio volumio[1194]: verbose: ControllerMpd::parseState Nov 03 21:57:04 volumio volumio[1194]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 03 21:57:04 volumio volumio[1194]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 03 21:57:04 volumio volumio[1194]: verbose: ControllerMpd::parseTrackInfo Nov 03 21:57:04 volumio volumio[1194]: info: ControllerMpd::pushState Nov 03 21:57:04 volumio volumio[1194]: info: CoreCommandRouter::servicePushState Nov 03 21:57:04 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:04 volumio volumio[1194]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30479,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"131 Kbps","isStreaming":false,"title":"icecast.audio","artist":"YleX","album":null,"uri":"https://icecast.live.yle.fi/radio/YleX/icecast.audio","trackType":"audio"} Nov 03 21:57:04 volumio volumio[1194]: verbose: CURRENT POSITION 0 Nov 03 21:57:04 volumio volumio[1194]: info: CoreStateMachine::syncState stateService play Nov 03 21:57:04 volumio volumio[1194]: info: CoreStateMachine::syncState currentStatus play Nov 03 21:57:04 volumio volumio[1194]: info: Received an update from plugin. extracting info from payload Nov 03 21:57:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 03 21:57:04 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:57:04 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:04 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 21:57:04 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:57:04 volumio volumio[1194]: info: CoreStateMachine::pushState Nov 03 21:57:04 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 21:57:04 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 21:57:04 volumio volumio[1194]: info: ------------------------------ 9ms Nov 03 21:57:04 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:57:04 volumio volumio[1194]: info: PeppyMeterBasic ---peppymeterbasic status play Nov 03 21:57:04 volumio sudo[5029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:57:04 volumio sudo[5029]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:57:04 volumio sudo[5029]: pam_unix(sudo:session): session closed for user root Nov 03 21:57:04 volumio volumio[1194]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Nov 03 21:57:04 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:57:04 volumio sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Nov 03 21:57:04 volumio sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 21:57:04 volumio sudo[5032]: pam_unix(sudo:session): session closed for user root Nov 03 21:57:04 volumio volumio[1194]: info: peppymeterbasic Daemon Started Nov 03 21:57:04 volumio go-librespot[3262]: time="2025-11-03T21:57:04+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.8.196:35458->34.158.1.133:80: read: connection reset by peer" Nov 03 21:57:10 volumio volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 21:57:10 volumio volumio[1194]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Nov 03 21:57:10 volumio volumio[1194]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Nov 03 21:57:10 volumio volumio[1194]: errno: -3007, Nov 03 21:57:10 volumio volumio[1194]: code: 'ENOTFOUND', Nov 03 21:57:10 volumio volumio[1194]: syscall: 'getaddrinfo', Nov 03 21:57:10 volumio volumio[1194]: hostname: 'cdn-images.dzcdn.net' Nov 03 21:57:10 volumio volumio[1194]: } Nov 03 21:57:10 volumio volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 21:57:10 volumio sudo[5057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 21:56 Nov 03 21:57:10 volumio sudo[5057]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"