-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-08-14 13:48:26 CEST. -- Aug 14 13:47:03 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 14 13:47:16 volumio volumio[1070]: error: MyVolumio Plugin failed to start in a timely fashion Aug 14 13:47:16 volumio volumio[1070]: info: BOOT COMPLETED Aug 14 13:47:16 volumio volumio[1070]: [Metrics] CommandRouter: 30s 322.05ms Aug 14 13:47:16 volumio volumio[1070]: info: CoreCommandRouter::volumiosetStartupVolume Aug 14 13:47:16 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 13:47:16 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 13:47:16 volumio volumio[1070]: info: CoreCommandRouter::Close All Modals sent Aug 14 13:47:16 volumio volumio[1070]: info: CoreCommandRouter::Close All Modals sent Aug 14 13:47:16 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 14 13:47:17 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 14 13:47:17 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 13:47:17 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 14 13:47:23 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 13:47:23 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 13:47:23 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 14 13:47:39 volumio ntpd[827]: Soliciting pool server 193.149.0.217 Aug 14 13:47:40 volumio ntpd[827]: Soliciting pool server 208.85.20.220 Aug 14 13:47:41 volumio ntpd[827]: Soliciting pool server 162.159.200.123 Aug 14 13:47:42 volumio ntpd[827]: Soliciting pool server 217.71.200.210 Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:47:43 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:43 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:47:43 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.1.107:3000 from 192.168.1.110 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:43 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 14 13:47:43 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 14 13:47:45 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 13:47:45 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:47:45 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Aug 14 13:47:45 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 13:47:45 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:47:45 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Aug 14 13:47:46 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.110 UA: Mozilla/5.0 (Linux; Android 11; CPH1951 Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 14 13:47:47 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 13:47:47 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:47:47 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Aug 14 13:47:47 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 13:47:47 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:47:47 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Aug 14 13:47:47 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.110 UA: Mozilla/5.0 (Linux; Android 11; CPH1951 Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:47 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 14 13:47:47 volumio volumio[1070]: info: Received Get System Info Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:47:47 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:47 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:47 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:47 volumio volumio[1070]: info: Listing playlists Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 14 13:47:47 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::volumioPlay Aug 14 13:47:48 volumio volumio[1070]: info: CoreStateMachine::play index undefined Aug 14 13:47:48 volumio volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 14 13:47:48 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:48 volumio volumio[1070]: info: CoreStateMachine::startPlaybackTimer Aug 14 13:47:48 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:48 volumio volumio[1070]: info: [1723636068600] ControllerWebradio::clearAddPlayTrack Aug 14 13:47:48 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Aug 14 13:47:48 volumio volumio[1070]: info: sendMpdCommand stop took 3 milliseconds Aug 14 13:47:48 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand clear Aug 14 13:47:48 volumio volumio[1070]: info: Aug 14 13:47:48 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:48 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:48 volumio volumio[1070]: info: sendMpdCommand clear took 4 milliseconds Aug 14 13:47:48 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand load "https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3" Aug 14 13:47:48 volumio volumio[1070]: info: Aug 14 13:47:48 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:48 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:48 volumio volumio[1070]: info: Aug 14 13:47:48 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:48 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:48 volumio volumio[1070]: error: updateQueue error: null Aug 14 13:47:48 volumio volumio[1070]: info: ------------------------------ 15ms Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 13:47:48 volumio volumio[1070]: info: Received Get System Info Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:47:48 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:48 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:48 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:47:49 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 14 13:47:50 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand add "https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3" Aug 14 13:47:50 volumio volumio[1070]: error: updateQueue error: null Aug 14 13:47:50 volumio volumio[1070]: error: updateQueue error: null Aug 14 13:47:50 volumio volumio[1070]: info: Aug 14 13:47:50 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:50 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:50 volumio volumio[1070]: info: ------------------------------ 1965ms Aug 14 13:47:50 volumio volumio[1070]: info: ------------------------------ 1963ms Aug 14 13:47:50 volumio volumio[1070]: info: sendMpdCommand add "https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3" took 4 milliseconds Aug 14 13:47:50 volumio volumio[1070]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 14 13:47:50 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand play Aug 14 13:47:50 volumio volumio[1070]: info: Aug 14 13:47:50 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:50 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:50 volumio volumio[1070]: info: Aug 14 13:47:50 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:50 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:50 volumio volumio[1070]: info: ------------------------------ 12ms Aug 14 13:47:50 volumio volumio[1070]: info: sendMpdCommand play took 9 milliseconds Aug 14 13:47:50 volumio volumio[1070]: info: ------------------------------ 8ms Aug 14 13:47:50 volumio volumio[1070]: info: ------------------------------ 6ms Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 49 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 51 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 49 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 6 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 71ms Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio volumio[1070]: info: Aug 14 13:47:51 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 12 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand status took 9 milliseconds Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:51 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:51 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:51 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 148ms Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 149ms Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 109ms Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 71 milliseconds Aug 14 13:47:51 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 72 milliseconds Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:51 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:51 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:51 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:51 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:51 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 126ms Aug 14 13:47:51 volumio volumio[1070]: info: ------------------------------ 126ms Aug 14 13:47:52 volumio volumio[1070]: info: VolumeController::SetAlsaVolume25 Aug 14 13:47:52 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:52 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:52 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:53 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:53 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces system playlist update Aug 14 13:47:53 volumio volumio[1070]: info: Ignoring MPD Status Update Aug 14 13:47:53 volumio volumio[1070]: info: Aug 14 13:47:53 volumio volumio[1070]: ---------------------------- MPD announces state update: player Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::getState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand status Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 11ms Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand status took 9 milliseconds Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 9ms Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand status took 7 milliseconds Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 6ms Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand status took 4 milliseconds Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseState Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 14 13:47:53 volumio volumio[1070]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:53 volumio volumio[1070]: verbose: ControllerMpd::parseTrackInfo Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1702,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:53 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:53 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1702,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:53 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:53 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: ControllerMpd::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1702,"duration":0,"samplerate":"32 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"net1-low.mp3","artist":null,"album":null,"uri":"https://net1-cope-rrcast.flumotion.com/cope/net1-low.mp3","trackType":"mp3"} Aug 14 13:47:53 volumio volumio[1070]: verbose: CURRENT POSITION 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus play Aug 14 13:47:53 volumio volumio[1070]: info: Received an update from plugin. extracting info from payload Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 75ms Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 73ms Aug 14 13:47:53 volumio volumio[1070]: info: ------------------------------ 71ms Aug 14 13:47:53 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:53 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:53 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:54 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:54 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:54 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:55 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:55 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:55 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:56 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:56 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:56 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:56 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:56 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:56 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:56 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:56 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:56 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 14 13:47:57 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:57 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:57 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:57 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:57 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:57 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:57 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:58 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:58 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:58 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:58 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:58 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:58 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:58 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:59 volumio volumio[1070]: info: VolumeController::SetAlsaVolume+ Aug 14 13:47:59 volumio volumio[1070]: info: CoreStateMachine::pushState Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:47:59 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:47:59 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 13:48:05 volumio volumio[1070]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 14 13:48:05 volumio volumio[1070]: info: Received Get System Version Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 13:48:05 volumio volumio[1070]: info: Received Get System Info Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:48:05 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:48:05 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:48:07 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 13:48:07 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:48:07 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Aug 14 13:48:07 volumio sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 13:48:07 volumio sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:48:07 volumio sudo[1319]: pam_unix(sudo:session): session closed for user root Aug 14 13:48:08 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.110 UA: Mozilla/5.0 (Linux; Android 11; CPH1951 Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 14 13:48:08 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 13:48:08 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:48:08 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Aug 14 13:48:08 volumio sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 13:48:08 volumio sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 13:48:08 volumio sudo[1325]: pam_unix(sudo:session): session closed for user root Aug 14 13:48:08 volumio volumio[1070]: verbose: New Socket.io Connection to 192.168.1.107 from 192.168.1.110 UA: Mozilla/5.0 (Linux; Android 11; CPH1951 Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 14 13:48:08 volumio volumio[1070]: info: Received Get System Info Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:48:08 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:48:08 volumio volumio[1070]: info: Listing playlists Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 14 13:48:08 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::volumioGetQueue Aug 14 13:48:10 volumio volumio[1070]: info: CoreStateMachine::getQueue Aug 14 13:48:10 volumio volumio[1070]: info: CorePlayQueue::getQueue Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 13:48:10 volumio volumio[1070]: info: Received Get System Info Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 13:48:10 volumio volumio[1070]: info: Discovery: Getting this device information Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 13:48:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 14 13:48:14 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 13:48:14 volumio volumio[1070]: info: CURURI: music-library Aug 14 13:48:14 volumio volumio[1070]: info: Preload queue cleared Aug 14 13:48:15 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 13:48:15 volumio volumio[1070]: info: CURURI: music-library/USB Aug 14 13:48:15 volumio volumio[1070]: info: Preload queue cleared Aug 14 13:48:16 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 13:48:16 volumio volumio[1070]: info: CURURI: music-library/USB/Nuevo_vol Aug 14 13:48:16 volumio volumio[1070]: info: Preload queue cleared Aug 14 13:48:18 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 14 13:48:19 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 13:48:19 volumio volumio[1070]: info: CURURI: music-library/USB/Nuevo_vol/[Discography] ACDC 1973 - 2006 Aug 14 13:48:19 volumio volumio[1070]: info: Preload queue cleared Aug 14 13:48:21 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 13:48:21 volumio volumio[1070]: info: CURURI: music-library/USB/Nuevo_vol/[Discography] ACDC 1973 - 2006/Discografia ACDC (Australia) - 1973 - 2006 - (+ australian albums + rarities). Mp3 por jjpower Aug 14 13:48:21 volumio volumio[1070]: info: Preload queue cleared Aug 14 13:48:25 volumio volumio[1070]: Searching plugin music_service/mpd Aug 14 13:48:25 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 14 13:48:25 volumio volumio[1070]: info: All search sources collected, pushing search results Aug 14 13:48:25 volumio volumio[1070]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dire%20Straits/Love%20Over%20Gold/a6e666d1-935a-4b08-94fb-4b76ac087759.jpg' Aug 14 13:48:26 volumio volumio[1070]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 14 13:48:26 volumio volumio[1070]: Searching plugin music_service/mpd Aug 14 13:48:26 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 14 13:48:26 volumio volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 14 13:48:26 volumio volumio[1070]: Error: certificate is not yet valid Aug 14 13:48:26 volumio volumio[1070]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Aug 14 13:48:26 volumio volumio[1070]: at TLSSocket.emit (events.js:315:20) Aug 14 13:48:26 volumio volumio[1070]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Aug 14 13:48:26 volumio volumio[1070]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Aug 14 13:48:26 volumio volumio[1070]: code: 'CERT_NOT_YET_VALID' Aug 14 13:48:26 volumio volumio[1070]: } Aug 14 13:48:26 volumio volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 14 13:48:26 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-14 13:47 Aug 14 13:48:26 volumio sudo[1337]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"