-- Logs begin at Fri 2024-11-08 18:58:28 CET, end at Sat 2024-11-30 09:39:08 CET. -- Nov 30 09:38:38 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:38:38 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:38:38 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:38:38 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:38 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:38 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:38:38 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.62 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Nov 30 09:38:39 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:39 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:39 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 30 09:38:39 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 30 09:38:40 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:38:40 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:38:40 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:38:40 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:40 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:40 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:38:44 volumio sudo[7896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 30 09:38:44 volumio sudo[7896]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:38:44 volumio sudo[7896]: pam_unix(sudo:session): session closed for user root Nov 30 09:38:44 volumio sudo[7899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 09:38:44 volumio sudo[7899]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:38:44 volumio sudo[7899]: pam_unix(sudo:session): session closed for user root Nov 30 09:38:44 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.62 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Build/RKQ1.201004.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 30 09:38:45 volumio sudo[7902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 30 09:38:45 volumio sudo[7902]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:38:45 volumio sudo[7902]: pam_unix(sudo:session): session closed for user root Nov 30 09:38:45 volumio sudo[7905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 09:38:45 volumio sudo[7905]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:38:45 volumio sudo[7905]: pam_unix(sudo:session): session closed for user root Nov 30 09:38:45 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.62 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Build/RKQ1.201004.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/131.0.6778.39 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:45 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 09:38:45 volumio volumio[966]: info: Received Get System Info Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:38:45 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:45 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:45 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:45 volumio volumio[966]: info: Listing playlists Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 30 09:38:45 volumio volumio[966]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::play index undefined Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: info: ControllerMpd::resume Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 30 09:38:46 volumio volumio[966]: info: Nov 30 09:38:46 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:46 volumio volumio[966]: info: sendMpdCommand play took 38 milliseconds Nov 30 09:38:46 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:46 volumio volumio[966]: info: Nov 30 09:38:46 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:46 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:46 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Nov 30 09:38:46 volumio volumio[966]: info: sendMpdCommand status took 1 milliseconds Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:46 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 09:38:46 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:46 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:46 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42217,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:46 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus pause Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:46 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":42217,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:46 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:46 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:46 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:46 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:46 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:46 volumio volumio[966]: info: ------------------------------ 17ms Nov 30 09:38:46 volumio volumio[966]: info: ------------------------------ 16ms Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:46 volumio volumio[966]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 09:38:47 volumio volumio[966]: info: Received Get System Info Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:38:47 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:47 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:38:47 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 30 09:38:48 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 09:38:48 volumio volumio[966]: info: Received Get System Info Nov 30 09:38:48 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:38:48 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:38:48 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:38:48 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:38:48 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:48 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioSeek Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::seek Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: TRACKBLOCK {"uri":"mnt/USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","service":"mpd","name":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=880&web=Warner%20Music%20Italy//extralarge&path=%2FUSB%2FMusica%2Fplaylist%20Emi&metadata=false","duration":239,"samplerate":44.1,"bitdepth":"32 bit","trackType":"mp4","channels":2} Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: ControllerMpd::seek Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:51 volumio volumio[966]: error: null Nov 30 09:38:51 volumio volumio[966]: info: Nov 30 09:38:51 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:51 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:51 volumio volumio[966]: info: Nov 30 09:38:51 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:51 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:51 volumio volumio[966]: info: sendMpdCommand status took 1 milliseconds Nov 30 09:38:51 volumio volumio[966]: info: sendMpdCommand status took 0 milliseconds Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:51 volumio volumio[966]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 30 09:38:51 volumio volumio[966]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:51 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:51 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106000,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:51 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:51 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:51 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106000,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:51 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:51 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:51 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:51 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:51 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:51 volumio volumio[966]: info: ------------------------------ 14ms Nov 30 09:38:51 volumio volumio[966]: info: ------------------------------ 16ms Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioSeek Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::seek Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: TRACKBLOCK {"uri":"mnt/USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","service":"mpd","name":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=880&web=Warner%20Music%20Italy//extralarge&path=%2FUSB%2FMusica%2Fplaylist%20Emi&metadata=false","duration":239,"samplerate":44.1,"bitdepth":"32 bit","trackType":"mp4","channels":2} Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: ControllerMpd::seek Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:52 volumio volumio[966]: error: null Nov 30 09:38:52 volumio volumio[966]: info: Nov 30 09:38:52 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:52 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:52 volumio volumio[966]: info: Nov 30 09:38:52 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:52 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:52 volumio volumio[966]: info: sendMpdCommand status took 11 milliseconds Nov 30 09:38:52 volumio volumio[966]: info: sendMpdCommand status took 3 milliseconds Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:52 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 09:38:52 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:52 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:52 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7000,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:52 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:52 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:52 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7479,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:52 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:52 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:52 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:52 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:52 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:52 volumio volumio[966]: info: ------------------------------ 31ms Nov 30 09:38:52 volumio volumio[966]: info: ------------------------------ 25ms Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioSeek Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::seek Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: TRACKBLOCK {"uri":"mnt/USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","service":"mpd","name":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=880&web=Warner%20Music%20Italy//extralarge&path=%2FUSB%2FMusica%2Fplaylist%20Emi&metadata=false","duration":239,"samplerate":44.1,"bitdepth":"32 bit","trackType":"mp4","channels":2} Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: ControllerMpd::seek Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:53 volumio volumio[966]: error: null Nov 30 09:38:53 volumio volumio[966]: info: Nov 30 09:38:53 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:53 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:53 volumio volumio[966]: info: Nov 30 09:38:53 volumio volumio[966]: ---------------------------- MPD announces state update: player Nov 30 09:38:53 volumio volumio[966]: info: ControllerMpd::getState Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Nov 30 09:38:53 volumio volumio[966]: info: sendMpdCommand status took 3 milliseconds Nov 30 09:38:53 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::parseState Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 09:38:53 volumio volumio[966]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 30 09:38:53 volumio volumio[966]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 30 09:38:53 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Nov 30 09:38:53 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5000,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:53 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:53 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:53 volumio volumio[966]: info: ControllerMpd::pushState Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::servicePushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5000,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Arisa - La notte (Official Video)","artist":"Warner Music Italy","album":null,"uri":"USB/Musica/playlist Emi/Arisa - La notte (Official Video) (520p_25fps_H264-128kbit_AAC).mp4","trackType":"mp4"} Nov 30 09:38:53 volumio volumio[966]: verbose: CURRENT POSITION 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Nov 30 09:38:53 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:53 volumio volumio[966]: info: CoreStateMachine::pushState Nov 30 09:38:53 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:38:53 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Nov 30 09:38:53 volumio volumio[966]: info: ------------------------------ 19ms Nov 30 09:38:53 volumio volumio[966]: info: ------------------------------ 17ms Nov 30 09:38:55 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 30 09:38:57 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 09:38:57 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 30 09:38:57 volumio volumio[966]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 30 09:39:01 volumio volumio[966]: info: FusionDsp - Stopping FusionDsp service Nov 30 09:39:01 volumio volumio[966]: info: camilladsp stopping service pid 29857... Nov 30 09:39:01 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 30 09:39:01 volumio volumio[966]: info: camilladsp service terminated, instance 3 Nov 30 09:39:01 volumio volumio[966]: info: Disabling plugin fusiondsp Nov 30 09:39:01 volumio volumio[966]: info: Preparing to generate the ALSA configuration file Nov 30 09:39:01 volumio sudo[7951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Nov 30 09:39:01 volumio sudo[7951]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:01 volumio volumio[966]: info: Asound.conf file written Nov 30 09:39:01 volumio systemd[1]: Stopping FusionDsp Daemon... Nov 30 09:39:01 volumio volumio[23682]: Backend configuration: Nov 30 09:39:01 volumio volumio[23682]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 30 09:39:01 volumio volumio[23682]: active_config_txt: null Nov 30 09:39:01 volumio volumio[23682]: camilla_host: 127.0.0.1 Nov 30 09:39:01 volumio volumio[23682]: camilla_port: 9876 Nov 30 09:39:01 volumio volumio[23682]: coeff_dir: /data/INTERNAL/FusionDsp/filters Nov 30 09:39:01 volumio volumio[23682]: config_dir: /data/configuration/audio_interface/fusiondsp Nov 30 09:39:01 volumio volumio[23682]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Nov 30 09:39:01 volumio volumio[23682]: log_file: /tmp/camilladsp.log Nov 30 09:39:01 volumio volumio[23682]: on_get_active_config: null Nov 30 09:39:01 volumio volumio[23682]: on_set_active_config: null Nov 30 09:39:01 volumio volumio[23682]: port: 5011 Nov 30 09:39:01 volumio volumio[23682]: supported_capture_types: null Nov 30 09:39:01 volumio volumio[23682]: supported_playback_types: null Nov 30 09:39:01 volumio volumio[23682]: update_config_symlink: false Nov 30 09:39:01 volumio volumio[23682]: update_config_txt: false Nov 30 09:39:01 volumio volumio[23682]: ======== Running on http://0.0.0.0:5011 ======== Nov 30 09:39:01 volumio volumio[23682]: (Press CTRL+C to quit) Nov 30 09:39:01 volumio sudo[7954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 30 09:39:01 volumio sudo[7954]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:01 volumio sudo[7954]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:01 volumio volumio[966]: info: Output device has changed, restarting MPD Nov 30 09:39:01 volumio systemd[1]: fusiondsp.service: Succeeded. Nov 30 09:39:01 volumio systemd[1]: Stopped FusionDsp Daemon. Nov 30 09:39:01 volumio sudo[7951]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:01 volumio sudo[7959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 30 09:39:01 volumio sudo[7959]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:01 volumio sudo[7959]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:01 volumio volumio[966]: info: Output device has changed, restarting Shairport Sync Nov 30 09:39:01 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:01 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 09:39:01 volumio sudo[7962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 30 09:39:01 volumio sudo[7962]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:01 volumio systemd[1]: Stopping Music Player Daemon... Nov 30 09:39:02 volumio volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 09:39:02 volumio volumio[966]: info: Done. Nov 30 09:39:02 volumio volumio[966]: info: MPD Permissions set Nov 30 09:39:02 volumio volumio[966]: info: FusionDsp - Reporting Fusion DSP Disabled Nov 30 09:39:02 volumio volumio[966]: info: Removing fusiondspeq DSP Signal Path Element Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio systemd[1]: mpd.service: Succeeded. Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio systemd[1]: Stopped Music Player Daemon. Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:02 volumio volumio[966]: info: Starting Shairport Sync Nov 30 09:39:02 volumio sudo[7974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 09:39:02 volumio sudo[7974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:02 volumio systemd[1]: Starting Music Player Daemon... Nov 30 09:39:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 30 09:39:02 volumio sudo[7974]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:02 volumio volumio[966]: info: Shairport-Sync Started Nov 30 09:39:02 volumio sudo[7967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 30 09:39:02 volumio sudo[7967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:02 volumio sudo[7967]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:02 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 7985, instance 2, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 7992, instance 1, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 7999, instance 2, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8006, instance 1, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8013, instance 2, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8020, instance 1, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8027, instance 2, run: true Nov 30 09:39:02 volumio mpd[7984]: Nov 30 09:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8034, instance 1, run: true Nov 30 09:39:02 volumio systemd[1]: Started Music Player Daemon. Nov 30 09:39:02 volumio sudo[7962]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8043, instance 2, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8050, instance 1, run: true Nov 30 09:39:02 volumio volumio[966]: error: updateQueue error: null Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8057, instance 2, run: true Nov 30 09:39:02 volumio volumio[966]: info: camilladsp spawned new process with pid 8064, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8071, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8078, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8085, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8092, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8099, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8106, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8113, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8120, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8127, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8134, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8141, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8148, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8155, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8162, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8169, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8176, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8183, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8190, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8197, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8204, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8211, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8218, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8225, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8232, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8239, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8246, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 30 09:39:03 volumio volumio[966]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 30 09:39:03 volumio volumio[966]: info: Received Get System Version Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 09:39:03 volumio volumio[966]: info: Received Get System Info Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 09:39:03 volumio volumio[966]: info: Discovery: Getting this device information Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Nov 30 09:39:03 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Nov 30 09:39:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8253, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8260, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8267, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8274, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8281, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8288, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8295, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8302, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8309, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8316, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8323, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8330, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8336, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8344, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8351, instance 2, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8358, instance 1, run: true Nov 30 09:39:03 volumio volumio[966]: info: camilladsp spawned new process with pid 8365, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8372, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8379, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8386, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8393, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8400, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8407, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8414, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8421, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8428, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8435, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8442, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8449, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8456, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8463, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8470, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8477, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8484, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8491, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8498, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8505, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8511, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8519, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8526, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8533, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8540, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8547, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8554, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8561, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8568, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8575, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8582, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8589, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8596, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8603, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8610, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8617, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8624, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8631, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8638, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8645, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8652, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8659, instance 2, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8666, instance 1, run: true Nov 30 09:39:04 volumio volumio[966]: info: camilladsp spawned new process with pid 8673, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8680, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8687, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8694, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8701, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8708, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8715, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8722, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8729, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8736, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8743, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8750, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8757, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8764, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8771, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8778, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8785, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8792, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8799, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8806, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8813, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8820, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8827, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8834, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8841, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8848, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8855, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8862, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8869, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8876, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8883, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8890, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8897, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8904, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8911, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8918, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8925, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8932, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8939, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8946, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8953, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8960, instance 1, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8967, instance 2, run: true Nov 30 09:39:05 volumio volumio[966]: info: camilladsp spawned new process with pid 8974, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 8981, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 8988, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 8995, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9002, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9017, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9030, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9037, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9044, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9051, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9058, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9065, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9072, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9079, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9086, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9095, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9103, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9110, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9117, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9124, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9131, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9138, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9145, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9152, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9159, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9166, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9173, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9180, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9187, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9194, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9201, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9208, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9215, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9222, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9229, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9236, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9243, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9250, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9257, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9264, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9271, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9278, instance 2, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9285, instance 1, run: true Nov 30 09:39:06 volumio volumio[966]: info: camilladsp spawned new process with pid 9292, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9299, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9306, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9313, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9320, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9327, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9334, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9341, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9348, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9355, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9362, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9369, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9376, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9383, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9390, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9397, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9404, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9411, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9418, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9425, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9432, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9439, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9446, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9453, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9460, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9467, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9474, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9481, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9488, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9495, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9502, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9509, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9516, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9523, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9530, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9537, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9544, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9551, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9558, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9565, instance 1, run: true Nov 30 09:39:07 volumio volumio[966]: info: Starting Uninstall of plugin audio_interface - fusiondsp Nov 30 09:39:07 volumio volumio[966]: info: Uninstalling plugin fusiondsp Nov 30 09:39:07 volumio volumio[966]: info: Disabling plugin fusiondsp Nov 30 09:39:07 volumio volumio[966]: info: Preparing to generate the ALSA configuration file Nov 30 09:39:07 volumio volumio[966]: info: camilladsp spawned new process with pid 9572, instance 2, run: true Nov 30 09:39:07 volumio volumio[966]: info: Asound.conf file unchanged, so no further update is needed Nov 30 09:39:07 volumio volumio[966]: info: Output device has changed, restarting MPD Nov 30 09:39:07 volumio sudo[9580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 30 09:39:07 volumio sudo[9580]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:07 volumio sudo[9580]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:07 volumio volumio[966]: info: Output device has changed, restarting Shairport Sync Nov 30 09:39:07 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:07 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 09:39:07 volumio sudo[9583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 30 09:39:07 volumio sudo[9583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:08 volumio systemd[1]: Stopping Music Player Daemon... Nov 30 09:39:08 volumio volumio[966]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 30 09:39:08 volumio volumio[966]: info: Checking if uninstall.sh is present Nov 30 09:39:08 volumio systemd[1]: mpd.service: Succeeded. Nov 30 09:39:08 volumio systemd[1]: Stopped Music Player Daemon. Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid 9589, instance 1, run: true Nov 30 09:39:08 volumio volumio[966]: info: Executing uninstall.sh Nov 30 09:39:08 volumio systemd[1]: Starting Music Player Daemon... Nov 30 09:39:08 volumio volumio[966]: info: MPD Permissions set Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid 9599, instance 2, run: true Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 09:39:08 volumio sudo[9598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Nov 30 09:39:08 volumio sudo[9598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:08 volumio sudo[9588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 30 09:39:08 volumio sudo[9588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid 9612, instance 1, run: true Nov 30 09:39:08 volumio sudo[9588]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid 9622, instance 2, run: true Nov 30 09:39:08 volumio volumio[966]: info: Starting Shairport Sync Nov 30 09:39:08 volumio sudo[9630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/multi-user.target.wants/fusiondsp.service Nov 30 09:39:08 volumio sudo[9630]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:08 volumio sudo[9630]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:08 volumio sudo[9598]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:08 volumio sudo[9631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 30 09:39:08 volumio sudo[9631]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid 9633, instance 1, run: true Nov 30 09:39:08 volumio volumio[966]: info: Uninstall script completed Nov 30 09:39:08 volumio volumio[966]: info: Removing plugin fusiondsp from configuration Nov 30 09:39:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 30 09:39:08 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 30 09:39:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 30 09:39:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 30 09:39:08 volumio sudo[9631]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:08 volumio volumio[966]: info: Successfully removed fusiondsp configuration files Nov 30 09:39:08 volumio volumio[966]: info: Plugin folders cleanup Nov 30 09:39:08 volumio volumio[966]: info: Scanning into folder /volumio/app/plugins/ Nov 30 09:39:08 volumio volumio[966]: info: Scanning category audio_interface Nov 30 09:39:08 volumio volumio[966]: info: Scanning category miscellanea Nov 30 09:39:08 volumio volumio[966]: info: Scanning category music_service Nov 30 09:39:08 volumio volumio[966]: info: Scanning category plugins.json Nov 30 09:39:08 volumio volumio[966]: info: Scanning category system_controller Nov 30 09:39:08 volumio volumio[966]: info: Scanning category user_interface Nov 30 09:39:08 volumio volumio[966]: info: Scanning into folder /data/plugins/ Nov 30 09:39:08 volumio volumio[966]: info: Scanning category audio_interface Nov 30 09:39:08 volumio volumio[966]: info: Cleaning folder for fusiondsp Nov 30 09:39:08 volumio volumio[966]: info: Scanning category music_service Nov 30 09:39:08 volumio volumio[966]: info: Plugin folders cleanup completed Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 30 09:39:08 volumio volumio[966]: info: [] Nov 30 09:39:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 30 09:39:08 volumio volumio[966]: info: camilladsp spawned new process with pid undefined, instance 2, run: true Nov 30 09:39:08 volumio volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 09:39:08 volumio volumio[966]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Nov 30 09:39:08 volumio volumio[966]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 30 09:39:08 volumio volumio[966]: at onErrorNT (internal/child_process.js:465:16) Nov 30 09:39:08 volumio volumio[966]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 30 09:39:08 volumio volumio[966]: at runNextTicks (internal/process/task_queues.js:62:3) Nov 30 09:39:08 volumio volumio[966]: at processTimers (internal/timers.js:494:9) { Nov 30 09:39:08 volumio volumio[966]: errno: -2, Nov 30 09:39:08 volumio volumio[966]: code: 'ENOENT', Nov 30 09:39:08 volumio volumio[966]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 30 09:39:08 volumio volumio[966]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 30 09:39:08 volumio volumio[966]: spawnargs: [ Nov 30 09:39:08 volumio volumio[966]: '-p', Nov 30 09:39:08 volumio volumio[966]: 9876, Nov 30 09:39:08 volumio volumio[966]: '-o', Nov 30 09:39:08 volumio volumio[966]: '/tmp/camilladsp.log', Nov 30 09:39:08 volumio volumio[966]: '-l', Nov 30 09:39:08 volumio volumio[966]: 'warn', Nov 30 09:39:08 volumio volumio[966]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 30 09:39:08 volumio volumio[966]: ] Nov 30 09:39:08 volumio volumio[966]: } Nov 30 09:39:08 volumio volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 09:39:08 volumio mpd[9621]: Nov 30 09:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 30 09:39:08 volumio systemd[1]: Started Music Player Daemon. Nov 30 09:39:08 volumio sudo[9583]: pam_unix(sudo:session): session closed for user root Nov 30 09:39:08 volumio sudo[9662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 09:38 Nov 30 09:39:08 volumio sudo[9662]: 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"