-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-11-28 18:14:57 CET. -- Nov 28 18:13:11 volumio volumio[981]: info: Nov 28 18:13:11 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:11 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:11 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:11 volumio volumio[981]: info: sendMpdCommand status took 1 milliseconds Nov 28 18:13:11 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:11 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:11 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:11 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:11 volumio volumio[981]: info: CorePlayQueue::getTrack 10 Nov 28 18:13:11 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:13:11 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:11 volumio volumio[981]: info: CorePlayQueue::getTrack 10 Nov 28 18:13:11 volumio volumio[981]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 28 18:13:11 volumio volumio[981]: verbose: CURRENT POSITION 10 Nov 28 18:13:11 volumio volumio[981]: info: CoreStateMachine::syncState stateService stop Nov 28 18:13:11 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Nov 28 18:13:11 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:11 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Nov 28 18:13:11 volumio volumio[981]: info: ------------------------------ 28ms Nov 28 18:13:11 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:13:11 volumio volumio[981]: info: [ASDebug] CurState: stop PrevState: play Nov 28 18:13:11 volumio volumio[981]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:11 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:11 volumio volumio[981]: error: FusionDsp - WebSocket error: [object Object] Nov 28 18:13:11 volumio volumio[981]: error: FusionDsp - WebSocket error: [object Object] Nov 28 18:13:11 volumio volumio[981]: info: camilladsp spawned new process with pid 4080, instance 1, run: true Nov 28 18:13:11 volumio volumio[981]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 28 18:13:12 volumio volumio[981]: info: [ASDebug] Togle GPIO: OFF Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:33 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:13:33 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.31.137:3000 from 192.168.31.34 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 28 18:13:33 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 28 18:13:35 volumio sudo[4126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 18:13:35 volumio sudo[4126]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:13:35 volumio sudo[4126]: pam_unix(sudo:session): session closed for user root Nov 28 18:13:35 volumio sudo[4128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 18:13:35 volumio sudo[4128]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:13:35 volumio sudo[4128]: pam_unix(sudo:session): session closed for user root Nov 28 18:13:35 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.31.137 from 192.168.31.34 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Nov 28 18:13:35 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 18:13:35 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 28 18:13:36 volumio sudo[4132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 28 18:13:36 volumio sudo[4132]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:13:36 volumio sudo[4132]: pam_unix(sudo:session): session closed for user root Nov 28 18:13:36 volumio sudo[4134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 28 18:13:36 volumio sudo[4134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:13:36 volumio sudo[4134]: pam_unix(sudo:session): session closed for user root Nov 28 18:13:36 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.31.137 from 192.168.31.34 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_1_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::volumioGetVisibleSources Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:36 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 28 18:13:36 volumio volumio[981]: info: Received Get System Info Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:36 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:36 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:36 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:36 volumio volumio[981]: info: Listing playlists Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 28 18:13:36 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 18:13:37 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:37 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:37 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:37 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:37 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:37 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:13:37 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 28 18:13:38 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 18:13:38 volumio volumio[981]: info: Received Get System Info Nov 28 18:13:38 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:38 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:38 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:38 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:38 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:38 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::play index undefined Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::clearAddPlayTracks NAS/flac2/Enigma - The Cross of Changes/01 Second Chapter.flac Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand stop took 1 milliseconds Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand clear took 0 milliseconds Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/01 Second Chapter.flac" Nov 28 18:13:39 volumio volumio[981]: info: Nov 28 18:13:39 volumio volumio[981]: ---------------------------- MPD announces system playlist update Nov 28 18:13:39 volumio volumio[981]: info: Ignoring MPD Status Update Nov 28 18:13:39 volumio volumio[981]: info: Nov 28 18:13:39 volumio volumio[981]: ---------------------------- MPD announces system playlist update Nov 28 18:13:39 volumio volumio[981]: info: Ignoring MPD Status Update Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/01 Second Chapter.flac" took 1 milliseconds Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Nov 28 18:13:39 volumio volumio[981]: info: ------------------------------ 2ms Nov 28 18:13:39 volumio volumio[981]: info: ------------------------------ 2ms Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand play took 2 milliseconds Nov 28 18:13:39 volumio volumio[981]: info: Nov 28 18:13:39 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:39 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:39 volumio volumio[981]: info: Nov 28 18:13:39 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand status took 22 milliseconds Nov 28 18:13:39 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand status took 2 milliseconds Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:39 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"312 Kbps","isStreaming":false,"title":"01 Second Chapter.flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/01 Second Chapter.flac","trackType":"flac"} Nov 28 18:13:39 volumio volumio[981]: verbose: CURRENT POSITION 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Nov 28 18:13:39 volumio volumio[981]: info: ------------------------------ 27ms Nov 28 18:13:39 volumio volumio[981]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 18:13:39 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:39 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1555,"duration":135,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"312 Kbps","isStreaming":false,"title":"01 Second Chapter.flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/01 Second Chapter.flac","trackType":"flac"} Nov 28 18:13:39 volumio volumio[981]: verbose: CURRENT POSITION 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Nov 28 18:13:39 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:39 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:39 volumio volumio[981]: info: ------------------------------ 33ms Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:39 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: stop Nov 28 18:13:39 volumio volumio[981]: info: [ASDebug] Togle GPIO: ON Nov 28 18:13:39 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 18:13:39 volumio volumio[981]: info: Received Get System Info Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:39 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:39 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:39 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:39 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:39 volumio volumio[981]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 28 18:13:43 volumio volumio[981]: info: CoreCommandRouter::volumioGetQueue Nov 28 18:13:43 volumio volumio[981]: info: CoreStateMachine::getQueue Nov 28 18:13:43 volumio volumio[981]: info: CorePlayQueue::getQueue Nov 28 18:13:46 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 28 18:13:46 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 28 18:13:46 volumio volumio[981]: info: Preload queue cleared Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Nov 28 18:13:50 volumio volumio[981]: info: [1732814030024] [rad357pl] handleBrowseUri curUri: rad357pl Nov 28 18:13:50 volumio volumio[981]: info: [1732814030024] [rad357pl] getRadioContent url: rad357pl Nov 28 18:13:50 volumio volumio[981]: info: Preload queue cleared Nov 28 18:13:50 volumio volumio[981]: info: Preload queue cleared Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::ClearQueue Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::stop Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::serviceStop Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::serviceStop Nov 28 18:13:50 volumio volumio[981]: info: ControllerMpd::stop Nov 28 18:13:50 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::saveQueue Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::addQueueItems Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::addQueueItems Nov 28 18:13:50 volumio volumio[981]: info: Preload queue cleared Nov 28 18:13:50 volumio volumio[981]: info: Adding Item to queue: webrns/0 Nov 28 18:13:50 volumio volumio[981]: info: Exploding uri webrns/0 in service rad357pl Nov 28 18:13:50 volumio volumio[981]: info: [1732814030937] [rad357pl] explodeUri: webrns/0 Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::saveQueue Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Nov 28 18:13:50 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::play index 0 Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::stop Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::play index undefined Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:50 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Nov 28 18:13:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:50 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 18:13:50 volumio volumio[981]: info: [ASDebug] CurState: stop PrevState: play Nov 28 18:13:50 volumio volumio[981]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 28 18:13:50 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:50 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:50 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:50 volumio volumio[981]: info: Nov 28 18:13:50 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:50 volumio volumio[981]: info: sendMpdCommand stop took 64 milliseconds Nov 28 18:13:50 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:50 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:50 volumio volumio[981]: info: sendMpdCommand stop took 15 milliseconds Nov 28 18:13:50 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 18:13:51 volumio volumio[981]: info: Nov 28 18:13:51 volumio volumio[981]: ---------------------------- MPD announces system playlist update Nov 28 18:13:51 volumio volumio[981]: info: Ignoring MPD Status Update Nov 28 18:13:51 volumio volumio[981]: info: sendMpdCommand status took 22 milliseconds Nov 28 18:13:51 volumio volumio[981]: info: sendMpdCommand clear took 21 milliseconds Nov 28 18:13:51 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:51 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:51 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Nov 28 18:13:51 volumio volumio[981]: error: updateQueue error: null Nov 28 18:13:51 volumio volumio[981]: info: Nov 28 18:13:51 volumio volumio[981]: ---------------------------- MPD announces system playlist update Nov 28 18:13:51 volumio volumio[981]: info: Ignoring MPD Status Update Nov 28 18:13:51 volumio volumio[981]: info: ------------------------------ 7ms Nov 28 18:13:51 volumio volumio[981]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 28 18:13:51 volumio volumio[981]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 3 milliseconds Nov 28 18:13:51 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:51 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand play Nov 28 18:13:51 volumio volumio[981]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 18:13:51 volumio volumio[981]: info: ------------------------------ 38ms Nov 28 18:13:51 volumio volumio[981]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 28 18:13:51 volumio volumio[981]: info: ------------------------------ 22ms Nov 28 18:13:51 volumio volumio[981]: info: sendMpdCommand play took 15 milliseconds Nov 28 18:13:51 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 28 18:13:51 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:51 volumio volumio[981]: info: camilladsp spawned new process with pid 4212, instance 1, run: true Nov 28 18:13:51 volumio volumio[981]: info: [ASDebug] Togle GPIO: OFF Nov 28 18:13:53 volumio volumio[981]: info: Nov 28 18:13:53 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:53 volumio volumio[981]: info: Nov 28 18:13:53 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand status took 24 milliseconds Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:53 volumio volumio[981]: info: Nov 28 18:13:53 volumio volumio[981]: ---------------------------- MPD announces system playlist update Nov 28 18:13:53 volumio volumio[981]: info: Ignoring MPD Status Update Nov 28 18:13:53 volumio volumio[981]: info: Nov 28 18:13:53 volumio volumio[981]: ---------------------------- MPD announces state update: player Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::getState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand status Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand status took 7 milliseconds Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:53 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:53 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AC/DC - Touch Too Much","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 28 18:13:53 volumio volumio[981]: verbose: CURRENT POSITION 0 Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Nov 28 18:13:53 volumio volumio[981]: info: ------------------------------ 42ms Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:53 volumio volumio[981]: info: ------------------------------ 17ms Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand status took 14 milliseconds Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseState Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:53 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:53 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"AC/DC - Touch Too Much","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 28 18:13:53 volumio volumio[981]: verbose: CURRENT POSITION 0 Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Nov 28 18:13:53 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:53 volumio volumio[981]: info: ------------------------------ 77ms Nov 28 18:13:53 volumio volumio[981]: info: sendMpdCommand playlistinfo took 51 milliseconds Nov 28 18:13:53 volumio volumio[981]: verbose: ControllerMpd::parseTrackInfo Nov 28 18:13:53 volumio volumio[981]: info: ControllerMpd::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::servicePushState Nov 28 18:13:53 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Nov 28 18:13:53 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"AC/DC - Touch Too Much","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Nov 28 18:13:53 volumio volumio[981]: verbose: CURRENT POSITION 0 Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Nov 28 18:13:53 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:53 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:13:53 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:13:53 volumio volumio[981]: info: ------------------------------ 114ms Nov 28 18:13:53 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: stop Nov 28 18:13:53 volumio volumio[981]: info: [ASDebug] Togle GPIO: ON Nov 28 18:13:53 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:13:53 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:13:53 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:13:53 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:13:54 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:54 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:54 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:54 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:13:54 volumio volumio[981]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 18:13:54 volumio volumio[981]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 28 18:13:54 volumio volumio[981]: info: Received Get System Version Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 18:13:54 volumio volumio[981]: info: Received Get System Info Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:13:54 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:13:54 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:14:00 volumio volumio[981]: info: VolumeController::SetAlsaVolume95 Nov 28 18:14:00 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:00 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:00 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:00 volumio volumio[981]: info: FusionDsp - volume level for loudness 95 gain applied 0.00 Nov 28 18:14:00 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:00 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:00 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Nov 28 18:14:00 volumio volumio[981]: SPOTIFY: SPOTIFY VOLUME 100 Nov 28 18:14:00 volumio volumio[981]: SPOTIFY: VOLUMIO VOLUME 95 Nov 28 18:14:00 volumio volumio[981]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 18:14:00 volumio volumio[981]: info: Setting Spotify Volume from Volumio: 95 Nov 28 18:14:00 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:14:02 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: SETTING SPOTIFY VOLUME 95 Nov 28 18:14:02 volumio volumio[981]: info: Sending Spotify command with payload to local API: /player/volume Nov 28 18:14:02 volumio go-librespot[1458]: time="2024-11-28T18:14:02+01:00" level=debug msg="update volume to 62258/65535" Nov 28 18:14:02 volumio volumio[981]: info: VolumeController::SetAlsaVolume+ Nov 28 18:14:02 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:02 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: SPOTIFY VOLUME 95 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: VOLUMIO VOLUME 100 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 18:14:02 volumio volumio[981]: info: Setting Spotify Volume from Volumio: 100 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:02 volumio go-librespot[1458]: time="2024-11-28T18:14:02+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 28 18:14:02 volumio go-librespot[1458]: time="2024-11-28T18:14:02+01:00" level=trace msg="emitting websocket event: volume" Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: received: {"type":"volume","data":{"value":95,"max":100}} Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: RECEIVED SPOTIFY VOLUME 95 Nov 28 18:14:02 volumio volumio[981]: info: Setting Volumio Volume from Spotify: 95 Nov 28 18:14:02 volumio volumio[981]: info: VolumeController::SetAlsaVolume95 Nov 28 18:14:02 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:02 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - volume level for loudness 95 gain applied 0.00 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:02 volumio volumio[981]: info: VolumeController::SetAlsaVolume+ Nov 28 18:14:02 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:02 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:02 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: SPOTIFY VOLUME 95 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: VOLUMIO VOLUME 100 Nov 28 18:14:02 volumio volumio[981]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 28 18:14:02 volumio volumio[981]: info: Setting Spotify Volume from Volumio: 100 Nov 28 18:14:02 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:03 volumio volumio[981]: info: VolumeController::SetAlsaVolume+ Nov 28 18:14:03 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:03 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:03 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:03 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:03 volumio volumio[981]: info: VolumeController::SetAlsaVolume+ Nov 28 18:14:03 volumio volumio[981]: info: CoreStateMachine::pushState Nov 28 18:14:03 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 18:14:03 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Nov 28 18:14:03 volumio volumio[981]: info: [ASDebug] CurState: play PrevState: play Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - volume level for loudness 100 gain applied 0.00 Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - Loudness is ON true Nov 28 18:14:03 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 28 18:14:03 volumio volumio[981]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 28 18:14:04 volumio volumio[981]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Nov 28 18:14:04 volumio volumio[981]: info: Sending Spotify command with payload to local API: /player/volume Nov 28 18:14:04 volumio go-librespot[1458]: time="2024-11-28T18:14:04+01:00" level=debug msg="update volume to 65535/65535" Nov 28 18:14:04 volumio go-librespot[1458]: time="2024-11-28T18:14:04+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 28 18:14:04 volumio go-librespot[1458]: time="2024-11-28T18:14:04+01:00" level=trace msg="emitting websocket event: volume" Nov 28 18:14:04 volumio volumio[981]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Nov 28 18:14:04 volumio volumio[981]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Nov 28 18:14:10 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 28 18:14:10 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 28 18:14:10 volumio volumio[981]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 28 18:14:15 volumio volumio[981]: info: FusionDsp - Stopping FusionDsp service Nov 28 18:14:15 volumio volumio[981]: info: camilladsp stopping service pid 4212... Nov 28 18:14:15 volumio volumio[981]: info: camilladsp service terminated, instance 1 Nov 28 18:14:15 volumio volumio[981]: info: Disabling plugin fusiondsp Nov 28 18:14:15 volumio volumio[981]: info: Preparing to generate the ALSA configuration file Nov 28 18:14:15 volumio sudo[4384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Nov 28 18:14:15 volumio sudo[4384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:15 volumio systemd[1]: Stopping FusionDsp Daemon... Nov 28 18:14:15 volumio volumio[1527]: Backend configuration: Nov 28 18:14:15 volumio volumio[1527]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 28 18:14:15 volumio volumio[1527]: active_config_txt: null Nov 28 18:14:15 volumio volumio[1527]: camilla_host: 127.0.0.1 Nov 28 18:14:15 volumio volumio[1527]: camilla_port: 9876 Nov 28 18:14:15 volumio volumio[1527]: coeff_dir: /data/INTERNAL/FusionDsp/filters Nov 28 18:14:15 volumio volumio[1527]: config_dir: /data/configuration/audio_interface/fusiondsp Nov 28 18:14:15 volumio volumio[1527]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 28 18:14:15 volumio volumio[1527]: log_file: /tmp/camilladsp.log Nov 28 18:14:15 volumio volumio[1527]: on_get_active_config: null Nov 28 18:14:15 volumio volumio[1527]: on_set_active_config: null Nov 28 18:14:15 volumio volumio[1527]: port: 5011 Nov 28 18:14:15 volumio volumio[1527]: supported_capture_types: null Nov 28 18:14:15 volumio volumio[1527]: supported_playback_types: null Nov 28 18:14:15 volumio volumio[1527]: update_config_symlink: false Nov 28 18:14:15 volumio volumio[1527]: update_config_txt: false Nov 28 18:14:15 volumio volumio[1527]: ======== Running on http://0.0.0.0:5011 ======== Nov 28 18:14:15 volumio volumio[1527]: (Press CTRL+C to quit) Nov 28 18:14:15 volumio volumio[981]: info: Asound.conf file written Nov 28 18:14:15 volumio sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 28 18:14:15 volumio sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:15 volumio sudo[4387]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:15 volumio volumio[981]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Nov 28 18:14:15 volumio volumio[981]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Nov 28 18:14:15 volumio volumio[981]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Nov 28 18:14:15 volumio volumio[981]: info: Output device has changed, restarting MPD Nov 28 18:14:15 volumio systemd[1]: fusiondsp.service: Succeeded. Nov 28 18:14:15 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 28 18:14:15 volumio sudo[4384]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:15 volumio sudo[4392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 18:14:15 volumio sudo[4392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:15 volumio sudo[4392]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:15 volumio volumio[981]: info: Output device has changed, restarting Shairport Sync Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 18:14:15 volumio sudo[4395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 18:14:15 volumio sudo[4395]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:15 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 18:14:15 volumio volumio[981]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 18:14:15 volumio volumio[981]: info: Done. Nov 28 18:14:15 volumio systemd[1]: mpd.service: Succeeded. Nov 28 18:14:15 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 18:14:15 volumio volumio[981]: info: MPD Permissions set Nov 28 18:14:15 volumio volumio[981]: info: FusionDsp - Reporting Fusion DSP Disabled Nov 28 18:14:15 volumio volumio[981]: info: Removing fusiondspeq DSP Signal Path Element Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 18:14:15 volumio volumio[981]: info: Starting Shairport Sync Nov 28 18:14:15 volumio systemd[1]: Starting Music Player Daemon... Nov 28 18:14:15 volumio sudo[4406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 18:14:15 volumio sudo[4406]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 18:14:15 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 18:14:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 18:14:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 18:14:16 volumio sudo[4406]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:16 volumio volumio[981]: info: Shairport-Sync Started Nov 28 18:14:16 volumio sudo[4400]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 18:14:16 volumio sudo[4400]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:16 volumio sudo[4400]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:16 volumio volumio[981]: error: Upnp client error: Error: This socket has been ended by the other party Nov 28 18:14:16 volumio mpd[4411]: Nov 28 18:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 18:14:16 volumio systemd[1]: Started Music Player Daemon. Nov 28 18:14:16 volumio sudo[4395]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:16 volumio volumio[981]: error: updateQueue error: null Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] onStop: Stopping Plugin. Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachAllListeners: 1,2,3 Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachAllListeners: 1,2 Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachAllListeners: 1 Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachAllListeners: Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachAllListeners: end of recursion. Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2]{"_events":{},"_eventsCount":0,"_closesNeeded":3,"_closesGot":0,"connected":false,"signalCode":null,"exitCode":null,"killed":false,"spawnfile":"/bin/cat","_handle":{"pid":1656},"spawnargs":["/bin/cat","/dev/input/by-path/platform-rotary@17-event"],"pid":1656,"stdin":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdout":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stderr":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdio":[{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null}]} Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: successfully killed handler process Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2]{"_events":{},"_eventsCount":0,"_closesNeeded":3,"_closesGot":0,"connected":false,"signalCode":null,"exitCode":null,"killed":false,"spawnfile":"/bin/cat","_handle":{"pid":1660},"spawnargs":["/bin/cat","/dev/input/by-path/platform-button@18-event"],"pid":1660,"stdin":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdout":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stderr":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdio":[{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null}]} Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: successfully killed handler process Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2]{"_events":{},"_eventsCount":0,"_closesNeeded":3,"_closesGot":0,"connected":false,"signalCode":null,"exitCode":null,"killed":false,"spawnfile":"/bin/cat","_handle":{"pid":1664},"spawnargs":["/bin/cat","/dev/input/by-path/platform-rotary@1b-event"],"pid":1664,"stdin":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdout":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stderr":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdio":[{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null}]} Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: successfully killed handler process Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2]{"_events":{},"_eventsCount":0,"_closesNeeded":3,"_closesGot":0,"connected":false,"signalCode":null,"exitCode":null,"killed":false,"spawnfile":"/bin/cat","_handle":{"pid":1669},"spawnargs":["/bin/cat","/dev/input/by-path/platform-button@11-event"],"pid":1669,"stdin":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdout":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stderr":{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},"stdio":[{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":null,"ended":false,"endEmitted":false,"reading":false,"sync":true,"needReadable":false,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null,"readable":false},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":1,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null},{"connecting":false,"_hadError":false,"_parent":null,"_host":null,"_readableState":{"objectMode":false,"highWaterMark":16384,"buffer":{"head":null,"tail":null,"length":0},"length":0,"pipes":[],"flowing":true,"ended":false,"endEmitted":false,"reading":true,"sync":false,"needReadable":true,"emittedReadable":false,"readableListening":false,"resumeScheduled":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"destroyed":false,"errored":null,"closed":false,"closeEmitted":false,"defaultEncoding":"utf8","awaitDrainWriters":null,"multiAwaitDrain":false,"readingMore":false,"decoder":null,"encoding":null},"_events":{},"_eventsCount":2,"_writableState":{"objectMode":false,"highWaterMark":16384,"finalCalled":false,"needDrain":false,"ending":false,"ended":false,"finished":false,"destroyed":false,"decodeStrings":false,"defaultEncoding":"utf8","length":0,"writing":false,"corked":0,"sync":true,"bufferProcessing":false,"writecb":null,"writelen":0,"afterWriteTickInfo":null,"buffered":[],"bufferedIndex":0,"allBuffers":true,"allNoop":true,"pendingcb":0,"prefinished":false,"errorEmitted":false,"emitClose":false,"autoDestroy":false,"errored":null,"closed":false,"writable":false,"closeEmitted":false},"allowHalfOpen":false,"_sockname":null,"_pendingData":null,"_pendingEncoding":"","server":null,"_server":null}]} Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: successfully killed handler process Nov 28 18:14:30 volumio volumio[981]: info: [ROTARYENCODER2] detachListener: no handler process to kill Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: 1,2,3 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: 2,3 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: 3 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: end of recursion. Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: rotary Nov 28 18:14:31 volumio sudo[4474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4474]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=27pinB=25 in Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: rotary-encoder pin_a=23 pin_b=22 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 1: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: 2: rotary-encoder pin_a=27 pin_b=25 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 3: gpio-key gpio=17 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2 uses overlay 2 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 2 Nov 28 18:14:31 volumio sudo[4477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 Nov 28 18:14:31 volumio sudo[4477]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio kernel: input: button@11 as /devices/platform/button@11/input/input5 Nov 28 18:14:31 volumio sudo[4477]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 2 returned: Nov 28 18:14:31 volumio sudo[4490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4490]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4490]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: rotary-encoder pin_a=23 pin_b=22 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 1: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: 2: gpio-key gpio=17 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: button Nov 28 18:14:31 volumio sudo[4493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4493]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=17pinB=undefined in Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: rotary-encoder pin_a=23 pin_b=22 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 1: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: 2: gpio-key gpio=17 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: button 2 uses overlay 2 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 2 Nov 28 18:14:31 volumio sudo[4496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 Nov 28 18:14:31 volumio sudo[4496]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio systemd-udevd[4484]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 28 18:14:31 volumio systemd-logind[557]: Failed to open /dev/input/event3: No such file or directory Nov 28 18:14:31 volumio startx[1456]: (EE) client bug: Invalid path /dev/input/event3 Nov 28 18:14:31 volumio sudo[4496]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 2 returned: Nov 28 18:14:31 volumio sudo[4509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4509]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: rotary-encoder pin_a=23 pin_b=22 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 1: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: overlays removed Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: rotary Nov 28 18:14:31 volumio sudo[4512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4512]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=23pinB=22 in Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: rotary-encoder pin_a=23 pin_b=22 relative_axis=true steps-per-period=1 Nov 28 18:14:31 volumio volumio[981]: 1: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1 uses overlay 0 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 0 Nov 28 18:14:31 volumio sudo[4515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Nov 28 18:14:31 volumio sudo[4515]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio kernel: input: button@18 as /devices/platform/button@18/input/input6 Nov 28 18:14:31 volumio sudo[4515]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 0 returned: Nov 28 18:14:31 volumio sudo[4525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4525]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4525]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: button Nov 28 18:14:31 volumio sudo[4528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:31 volumio sudo[4528]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:31 volumio sudo[4528]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=24pinB=undefined in Overlays (in load order): Nov 28 18:14:31 volumio volumio[981]: 0: gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] checkOverlayExists: button 1 uses overlay 0 Nov 28 18:14:31 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 0 Nov 28 18:14:31 volumio sudo[4531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Nov 28 18:14:31 volumio sudo[4531]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 18:14:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 18:14:32 volumio volumio[981]: info: Discovery: Getting this device information Nov 28 18:14:32 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Nov 28 18:14:32 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 18:14:32 volumio systemd-udevd[4484]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 28 18:14:32 volumio systemd-logind[557]: Failed to open /dev/input/event1: No such file or directory Nov 28 18:14:32 volumio startx[1456]: (EE) client bug: Invalid path /dev/input/event1 Nov 28 18:14:32 volumio sudo[4531]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:32 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: 0 returned: Nov 28 18:14:32 volumio sudo[4542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 28 18:14:32 volumio sudo[4542]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 18:14:32 volumio sudo[4542]: pam_unix(sudo:session): session closed for user root Nov 28 18:14:32 volumio volumio[981]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: No overlays loaded Nov 28 18:14:32 volumio volumio[981]: info: [ROTARYENCODER2] uninstallAllOverlays: overlays removed Nov 28 18:14:32 volumio volumio[981]: info: [ROTARYENCODER2] getI18nString("TOAST_STOP_SUCCESS"):Plugin successfully stopped. Nov 28 18:14:32 volumio volumio[981]: info: [ROTARYENCODER2] onStop: Plugin successfully stopped. Nov 28 18:14:32 volumio volumio[981]: info: Disabling plugin rotaryencoder2 Nov 28 18:14:32 volumio volumio[981]: info: Done. Nov 28 18:14:46 volumio volumio[981]: info: [ASDebug] Port: 2 Nov 28 18:14:46 volumio volumio[981]: info: [ASDebug] Inverted: false Nov 28 18:14:46 volumio volumio[981]: info: [ASDebug] Delay: 720 Nov 28 18:14:56 volumio volumio[981]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 28 18:14:56 volumio volumio[981]: info: Uninstalling plugin ampswitch Nov 28 18:14:56 volumio volumio[981]: info: [ASDebug] Port: 2 Nov 28 18:14:56 volumio volumio[981]: info: [ASDebug] Inverted: false Nov 28 18:14:56 volumio volumio[981]: info: [ASDebug] Delay: 720 Nov 28 18:14:56 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 18:14:56 volumio volumio[981]: Error: EBADF: bad file descriptor, close Nov 28 18:14:56 volumio volumio[981]: at Object.closeSync (fs.js:439:3) Nov 28 18:14:56 volumio volumio[981]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 28 18:14:56 volumio volumio[981]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 28 18:14:56 volumio volumio[981]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 28 18:14:56 volumio volumio[981]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 28 18:14:56 volumio volumio[981]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 28 18:14:56 volumio volumio[981]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 28 18:14:56 volumio volumio[981]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Nov 28 18:14:56 volumio volumio[981]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1479:22) Nov 28 18:14:56 volumio volumio[981]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1254:45) Nov 28 18:14:56 volumio volumio[981]: at Socket.emit (events.js:315:20) Nov 28 18:14:56 volumio volumio[981]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 28 18:14:56 volumio volumio[981]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 28 18:14:56 volumio volumio[981]: errno: -9, Nov 28 18:14:56 volumio volumio[981]: syscall: 'close', Nov 28 18:14:56 volumio volumio[981]: code: 'EBADF' Nov 28 18:14:56 volumio volumio[981]: } Nov 28 18:14:56 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 18:14:57 volumio sudo[4658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 18:13 Nov 28 18:14:57 volumio sudo[4658]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"