-- Logs begin at Wed 2024-09-25 10:51:22 +09, end at Sun 2024-10-20 11:11:44 +09. -- Oct 20 11:11:04 volumio ntpd[22705]: 129.250.35.250 local addr 192.168.1.18 -> Oct 20 11:11:04 volumio ntpd[22705]: 162.159.200.123 local addr 192.168.1.18 -> Oct 20 11:11:13 volumio volumio[950]: info: CoreCommandRouter::volumioPlay Oct 20 11:11:13 volumio volumio[950]: info: CoreStateMachine::play index undefined Oct 20 11:11:13 volumio volumio[950]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 20 11:11:13 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:13 volumio volumio[950]: info: CoreStateMachine::startPlaybackTimer Oct 20 11:11:13 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:13 volumio volumio[950]: info: [1729390273571] ControllerCalmRadio::clearAddPlayTrack Oct 20 11:11:13 volumio volumio[950]: { Oct 20 11:11:13 volumio volumio[950]: uri: 'calmradio://10/638', Oct 20 11:11:13 volumio volumio[950]: service: 'calmradio', Oct 20 11:11:13 volumio volumio[950]: trackType: 'CalmRadio', Oct 20 11:11:13 volumio volumio[950]: radioType: 'web', Oct 20 11:11:13 volumio volumio[950]: name: 'PEACEFUL BACH', Oct 20 11:11:13 volumio volumio[950]: title: 'PEACEFUL BACH', Oct 20 11:11:13 volumio volumio[950]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/638/d5cf61d83accb5151f24292bed91a9172c7f26c56066cc5df41828731d50.png', Oct 20 11:11:13 volumio volumio[950]: type: 'webradio', Oct 20 11:11:13 volumio volumio[950]: samplerate: '', Oct 20 11:11:13 volumio volumio[950]: bitdepth: 0, Oct 20 11:11:13 volumio volumio[950]: channels: 0 Oct 20 11:11:13 volumio volumio[950]: } Oct 20 11:11:13 volumio volumio[950]: info: Calm Radio getStreamUrl for Cat 10 Chan 638 Oct 20 11:11:13 volumio volumio[950]: info: Getting Calm Radio channels data Oct 20 11:11:17 volumio volumio[950]: STREAMURL: https://streams.calmradio.com/api/2236/128/stream Oct 20 11:11:17 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand stop Oct 20 11:11:17 volumio volumio[950]: info: sendMpdCommand stop took 9 milliseconds Oct 20 11:11:17 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand clear Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: sendMpdCommand clear took 4 milliseconds Oct 20 11:11:17 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/2236/128/stream" Oct 20 11:11:17 volumio volumio[950]: error: updateQueue error: null Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 11ms Oct 20 11:11:17 volumio volumio[950]: info: sendMpdCommand addid "https://streams.calmradio.com/api/2236/128/stream" took 12 milliseconds Oct 20 11:11:17 volumio volumio[950]: verbose: MPD COMMAND [object Object] Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 16ms Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 9ms Oct 20 11:11:17 volumio volumio[950]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 20 11:11:17 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand play Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: error: Upnp client error: Error: This socket has been ended by the other party Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 20ms Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 22ms Oct 20 11:11:17 volumio volumio[950]: info: sendMpdCommand play took 15 milliseconds Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 9ms Oct 20 11:11:17 volumio volumio[950]: info: Oct 20 11:11:17 volumio volumio[950]: ---------------------------- MPD announces system playlist update Oct 20 11:11:17 volumio volumio[950]: info: Ignoring MPD Status Update Oct 20 11:11:17 volumio volumio[950]: info: ------------------------------ 2ms Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 8 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 8 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: info: Oct 20 11:11:19 volumio volumio[950]: ---------------------------- MPD announces state update: player Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::getState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand status Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 23 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 24 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand clearerror took 20 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand clearerror took 20 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 60ms Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 60ms Oct 20 11:11:19 volumio volumio[950]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 11:11:19 volumio volumio[950]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 49 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand status took 48 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand clearerror took 45 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand clearerror took 43 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 36 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseState Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 81ms Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 80ms Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 20 11:11:19 volumio volumio[950]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: verbose: ControllerMpd::parseTrackInfo Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ControllerMpd::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::servicePushState Oct 20 11:11:19 volumio volumio[950]: info: CorePlayQueue::getTrack 0 Oct 20 11:11:19 volumio volumio[950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"PEACEFUL BACH","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/2236/128/stream","trackType":""} Oct 20 11:11:19 volumio volumio[950]: verbose: CURRENT POSITION 0 Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState stateService pause Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::syncState currentStatus stop Oct 20 11:11:19 volumio volumio[950]: info: CoreStateMachine::pushState Oct 20 11:11:19 volumio volumio[950]: info: CoreCommandRouter::volumioPushState Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 79ms Oct 20 11:11:19 volumio volumio[950]: info: ------------------------------ 79ms Oct 20 11:11:19 volumio volumio[950]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 20 11:11:30 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:30 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 20 11:11:30 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 20 11:11:30 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 20 11:11:30 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 20 11:11:31 volumio volumio[950]: info: Received Get System Version Oct 20 11:11:31 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 20 11:11:39 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Oct 20 11:11:39 volumio volumio[950]: info: CoreCommandRouter::Reload Ui Oct 20 11:11:40 volumio sudo[23882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 11:11:40 volumio sudo[23882]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:40 volumio sudo[23882]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:40 volumio sudo[23885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 11:11:40 volumio sudo[23885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:40 volumio sudo[23885]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:40 volumio volumio[950]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 20 11:11:40 volumio volumio[950]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.12 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 20 11:11:40 volumio volumio[950]: info: Received Get System Info Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 11:11:40 volumio volumio[950]: info: Discovery: Getting this device information Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::volumioGetState Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 11:11:40 volumio volumio-remote-updater[707]: No test mode Oct 20 11:11:40 volumio volumio-remote-updater[707]: No alpha test mode Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:40 volumio volumio[950]: info: Retrieving Cloud Streaming UI Oct 20 11:11:40 volumio volumio[950]: info: Getting Tidal Cloud Configuration Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:40 volumio volumio[950]: info: Getting Qobuz Cloud Configuration Oct 20 11:11:40 volumio volumio[950]: info: Asking plugin for UI Config Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:40 volumio volumio[950]: info: Getting Spotify Cloud Configuration Oct 20 11:11:40 volumio volumio[950]: info: Asking plugin for UI Config Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:40 volumio volumio[950]: info: Saving Spotify Acccount Oct 20 11:11:40 volumio volumio[950]: info: Got Tidal Cloud Configuration Oct 20 11:11:40 volumio volumio[950]: info: Got it Oct 20 11:11:40 volumio volumio[950]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:40 volumio volumio[950]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 20 11:11:40 volumio volumio[950]: info: Received Get System Info Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 11:11:40 volumio volumio[950]: info: Discovery: Getting this device information Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::volumioGetState Oct 20 11:11:40 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:41 volumio volumio[950]: info: Retrieving Cloud Streaming UI Oct 20 11:11:41 volumio volumio[950]: info: Getting Tidal Cloud Configuration Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:41 volumio volumio[950]: info: Getting Qobuz Cloud Configuration Oct 20 11:11:41 volumio volumio[950]: info: Asking plugin for UI Config Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:41 volumio volumio[950]: info: Getting Spotify Cloud Configuration Oct 20 11:11:41 volumio volumio[950]: info: Asking plugin for UI Config Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:41 volumio volumio[950]: info: Saving Spotify Acccount Oct 20 11:11:41 volumio volumio[950]: info: Got Tidal Cloud Configuration Oct 20 11:11:41 volumio volumio[950]: info: Got it Oct 20 11:11:41 volumio volumio[950]: info: Got it Oct 20 11:11:41 volumio volumio[950]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 11:11:41 volumio volumio[950]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 20 11:11:41 volumio volumio-remote-updater[707]: No test mode Oct 20 11:11:41 volumio volumio-remote-updater[707]: No alpha test mode Oct 20 11:11:41 volumio volumio[950]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 20 11:11:41 volumio volumio[950]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 20 11:11:41 volumio volumio[950]: info: Loading i18n strings for locale ja Oct 20 11:11:41 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 20 11:11:41 volumio volumio[950]: info: Setting timezone to Etc/GMT-9 Oct 20 11:11:41 volumio sudo[23896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 20 11:11:41 volumio sudo[23896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:41 volumio sudo[23896]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:41 volumio systemd[1]: Starting Daily man-db regeneration... Oct 20 11:11:41 volumio systemd[1]: Starting Daily apt download activities... Oct 20 11:11:41 volumio sudo[23901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Etc/GMT-9 /etc/localtime Oct 20 11:11:41 volumio sudo[23901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:41 volumio sudo[23901]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio sudo[23911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 20 11:11:42 volumio sudo[23911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio sudo[23911]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio systemd[1]: man-db.service: Succeeded. Oct 20 11:11:42 volumio systemd[1]: Started Daily man-db regeneration. Oct 20 11:11:42 volumio sudo[23920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/GMT-9 Oct 20 11:11:42 volumio sudo[23920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio dbus-daemon[715]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=23923 comm="/usr/bin/timedatectl set-timezone Etc/GMT-9 ") Oct 20 11:11:42 volumio systemd[1]: Starting Time & Date Service... Oct 20 11:11:42 volumio dbus-daemon[715]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 20 11:11:42 volumio systemd[1]: Started Time & Date Service. Oct 20 11:11:42 volumio sudo[23920]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 11:11:42 volumio volumio[950]: Updating browse sources language Oct 20 11:11:42 volumio volumio[950]: Cannot find translation for source Calm Radio Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 11:11:42 volumio volumio[950]: Cannot find translation for source Calm Radio Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 11:11:42 volumio volumio[950]: info: Fetching Streaming Services browse cache Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 20 11:11:42 volumio volumio[950]: info: Loading i18n strings for locale en Oct 20 11:11:42 volumio volumio[950]: Updating browse sources language Oct 20 11:11:42 volumio volumio[950]: Cannot find translation for source Calm Radio Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 11:11:42 volumio volumio[950]: Cannot find translation for source Calm Radio Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 20 11:11:42 volumio volumio[950]: info: Setting timezone to Etc/Unknown Oct 20 11:11:42 volumio sudo[23933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 20 11:11:42 volumio sudo[23933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio sudo[23933]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio sudo[23936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Etc/Unknown /etc/localtime Oct 20 11:11:42 volumio sudo[23936]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio sudo[23936]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio sudo[23939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 20 11:11:42 volumio sudo[23939]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio sudo[23939]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio volumio[950]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Oct 20 11:11:42 volumio volumio[950]: error: Could not set timezone: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/localtime Oct 20 11:11:42 volumio volumio[950]: /bin/chmod: cannot operate on dangling symlink '/etc/localtime' Oct 20 11:11:42 volumio sudo[23942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/Unknown Oct 20 11:11:42 volumio sudo[23942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:42 volumio sudo[23942]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:42 volumio volumio[950]: Failed to set time zone: Invalid time zone 'Etc/Unknown' Oct 20 11:11:42 volumio volumio[950]: info: Could not set timezone, retrying Oct 20 11:11:42 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 11:11:43 volumio volumio[950]: info: Fetching Streaming Services browse cache Oct 20 11:11:43 volumio systemd[1]: apt-daily.service: Succeeded. Oct 20 11:11:43 volumio systemd[1]: Started Daily apt download activities. Oct 20 11:11:43 volumio volumio[950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 20 11:11:43 volumio sudo[23974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Etc/Unknown Oct 20 11:11:43 volumio sudo[23974]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 11:11:43 volumio sudo[23974]: pam_unix(sudo:session): session closed for user root Oct 20 11:11:43 volumio volumio[950]: Failed to set time zone: Invalid time zone 'Etc/Unknown' Oct 20 11:11:43 volumio volumio[950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 11:11:43 volumio volumio[950]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Etc/Unknown' Oct 20 11:11:43 volumio volumio[950]: Failed to set time zone: Invalid time zone 'Etc/Unknown' Oct 20 11:11:43 volumio volumio[950]: at checkExecSyncError (child_process.js:790:11) Oct 20 11:11:43 volumio volumio[950]: at execSync (child_process.js:863:15) Oct 20 11:11:43 volumio volumio[950]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Oct 20 11:11:43 volumio volumio[950]: at listOnTimeout (internal/timers.js:557:17) Oct 20 11:11:43 volumio volumio[950]: at processTimers (internal/timers.js:500:7) { Oct 20 11:11:43 volumio volumio[950]: status: 1, Oct 20 11:11:43 volumio volumio[950]: signal: null, Oct 20 11:11:43 volumio volumio[950]: output: [ Oct 20 11:11:43 volumio volumio[950]: null, Oct 20 11:11:43 volumio volumio[950]: '', Oct 20 11:11:43 volumio volumio[950]: "Failed to set time zone: Invalid time zone 'Etc/Unknown'\n" Oct 20 11:11:43 volumio volumio[950]: ], Oct 20 11:11:43 volumio volumio[950]: pid: 23973, Oct 20 11:11:43 volumio volumio[950]: stdout: '', Oct 20 11:11:43 volumio volumio[950]: stderr: "Failed to set time zone: Invalid time zone 'Etc/Unknown'\n" Oct 20 11:11:43 volumio volumio[950]: } Oct 20 11:11:43 volumio volumio[950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 11:11:44 volumio sudo[23985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-20 11:10 Oct 20 11:11:44 volumio sudo[23985]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"