-- Logs begin at Tue 2024-10-01 04:49:47 UTC, end at Tue 2024-10-01 11:54:11 UTC. -- Oct 01 11:53:13 volumio systemd[1]: Time has been changed Oct 01 11:53:15 volumio volumio[946]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent Oct 01 11:53:15 volumio volumio[946]: Express server listening on port 3000 Oct 01 11:53:15 volumio volumio[946]: [Metrics] WebUI: 10s 904.24ms Oct 01 11:53:15 volumio volumio[946]: Volumio Calling Home Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::resetVolumioState Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::getcurrentVolume Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 11:53:15 volumio volumio[946]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:2 load error: Remote I/O error Oct 01 11:53:15 volumio volumio[946]: info: Setting Device type: Raspberry PI Oct 01 11:53:15 volumio volumio[946]: info: MPD running with PID703 Oct 01 11:53:15 volumio volumio[946]: ,establishing connection Oct 01 11:53:15 volumio volumio[946]: info: VolumeController:: Volume=undefined Mute =false Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrackBlock Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 11:53:15 volumio volumio[946]: info: Reloading queue from file Oct 01 11:53:15 volumio volumio[946]: error: updateQueue error: null Oct 01 11:53:15 volumio volumio[946]: Cannot compose Albumart path Oct 01 11:53:15 volumio volumio[946]: Cannot compose Albumart path Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::setRepeat null single undefined Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::setRandom false Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:15 volumio volumio[946]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer hw:2 load error: Remote I/O error Oct 01 11:53:15 volumio volumio[946]: info: mDNS: A device disapperared from network Oct 01 11:53:15 volumio volumio[946]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 01 11:53:15 volumio volumio[946]: info: Starting Shairport Sync Oct 01 11:53:15 volumio volumio[946]: info: Starting Shairport Sync Oct 01 11:53:15 volumio sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 11:53:15 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:15 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 11:53:15 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 11:53:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 11:53:15 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 01 11:53:15 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 01 11:53:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 11:53:15 volumio sudo[1077]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:15 volumio volumio[946]: info: VolumeController:: Volume=undefined Mute =false Oct 01 11:53:15 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:15 volumio volumio[946]: info: Shairport-Sync Started Oct 01 11:53:15 volumio volumio[946]: Error adding Membership: Error: addMembership EINVAL Oct 01 11:53:15 volumio volumio[946]: info: Shairport-Sync Started Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:15 volumio volumio[946]: Discovery: adding 768a7084-d876-4e44-aeb6-d76c4d19422d Oct 01 11:53:15 volumio volumio[946]: info: mDNS: Found device Volumio Oct 01 11:53:15 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 01 11:53:15 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:16 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 01 11:53:16 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 11:53:16 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 01 11:53:16 volumio volumio[946]: Discovery: this is already registered, 768a7084-d876-4e44-aeb6-d76c4d19422d Oct 01 11:53:16 volumio volumio[946]: info: mDNS: Found device Volumio Oct 01 11:53:16 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 01 11:53:16 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:17 volumio volumio[946]: info: Volumio called home Oct 01 11:53:17 volumio volumio-remote-updater[626]: [2024-10-01 11:53:17] [connect] Successful connection Oct 01 11:53:17 volumio volumio-remote-updater[626]: [2024-10-01 11:53:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1727783597 101 Oct 01 11:53:17 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 11:53:17 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:17 volumio sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 11:53:17 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:17 volumio sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:17 volumio sudo[1110]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:17 volumio sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 01 11:53:17 volumio sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:17 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 01 11:53:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 01 11:53:17 volumio sudo[1118]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:17 volumio volumio[946]: info: Upmpdcli Daemon Started Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 01 11:53:26 volumio volumio[946]: info: Adding plugin bluetooth to MyMusic Plugins Oct 01 11:53:26 volumio volumio[946]: info: Adding plugin cd_controller to MyMusic Plugins Oct 01 11:53:26 volumio volumio[946]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 01 11:53:26 volumio volumio[946]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 01 11:53:26 volumio volumio[946]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 01 11:53:27 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 11:53:27 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 11:53:27 volumio volumio[946]: info: Starting MyVolumio Remote Streaming Endpoints Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 01 11:53:27 volumio volumio[946]: info: Streaming services startup Oct 01 11:53:27 volumio volumio[946]: info: Starting Streaming Daemon Oct 01 11:53:27 volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 01 11:53:27 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:27 volumio volumio[946]: info: MyVolumio not started Oct 01 11:53:27 volumio volumio[946]: info: Initializing device activation check Oct 01 11:53:27 volumio volumio[946]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 01 11:53:27 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 01 11:53:27 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 01 11:53:27 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 01 11:53:27 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:27 volumio volumio-streaming-daemon[1177]: ############################ Oct 01 11:53:27 volumio volumio-streaming-daemon[1177]: # Volumio Streaming Daemon # Oct 01 11:53:27 volumio volumio-streaming-daemon[1177]: # Running on port 7777 # Oct 01 11:53:27 volumio volumio-streaming-daemon[1177]: ############################ Oct 01 11:53:27 volumio volumio-streaming-daemon[1177]: Environment: production Oct 01 11:53:27 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 11:53:27 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:27 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:27 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 11:53:27 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 11:53:27 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::volumioGetState Oct 01 11:53:28 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 01 11:53:28 volumio volumio[946]: info: Listing playlists Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 01 11:53:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 01 11:53:28 volumio volumio[946]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B8%8D%E6%98%AF%E8%8A%B1%E7%81%AB%E5%91%80/TA/6c1dee7d-f800-46b8-9acb-a0e28a0fa9fe.jpg' Oct 01 11:53:30 volumio volumio-streaming-daemon[1177]: Environment: production Oct 01 11:53:30 volumio volumio[946]: info: Fetching Streaming Services browse cache Oct 01 11:53:38 volumio volumio[946]: info: CoreCommandRouter::volumioGetQueue Oct 01 11:53:38 volumio volumio[946]: info: CoreStateMachine::getQueue Oct 01 11:53:38 volumio volumio[946]: info: CorePlayQueue::getQueue Oct 01 11:53:38 volumio volumio[946]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A8%8B%E5%93%8D/%E5%9B%9B%E5%AD%A3%E4%BA%88%E4%BD%A0/f538cf4f-399d-491b-8f0e-06a0614c59fe.jpg' Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: info: Retrieving Cloud Streaming UI Oct 01 11:53:39 volumio volumio[946]: info: Getting Tidal Cloud Configuration Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: info: Getting Qobuz Cloud Configuration Oct 01 11:53:39 volumio volumio[946]: info: Asking plugin for UI Config Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: info: Getting Spotify Cloud Configuration Oct 01 11:53:39 volumio volumio[946]: info: Asking plugin for UI Config Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: info: Saving Spotify Acccount Oct 01 11:53:39 volumio volumio[946]: info: Got Tidal Cloud Configuration Oct 01 11:53:39 volumio volumio[946]: info: Got it Oct 01 11:53:39 volumio volumio[946]: info: Got it Oct 01 11:53:39 volumio volumio[946]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 11:53:39 volumio volumio[946]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 11:53:39 volumio volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 11:53:40 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 11:53:40 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 01 11:53:41 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 01 11:53:41 volumio volumio[946]: info: MPD Database updated - AlbumList cache refreshed Oct 01 11:53:41 volumio volumio[946]: info: Oct 01 11:53:41 volumio volumio[946]: ---------------------------- MPD announces state update: database Oct 01 11:53:41 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 01 11:53:41 volumio volumio[946]: info: Oct 01 11:53:41 volumio volumio[946]: ---------------------------- MPD announces state update: update Oct 01 11:53:41 volumio volumio[946]: info: ControllerMpd::getState Oct 01 11:53:41 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 01 11:53:41 volumio volumio[946]: info: ControllerMpd::getState Oct 01 11:53:41 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 01 11:53:41 volumio volumio[946]: Cannot compose Albumart path Oct 01 11:53:41 volumio volumio[946]: Cannot compose Albumart path Oct 01 11:53:42 volumio volumio[946]: Cannot compose Albumart path Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand status took 959 milliseconds Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand status took 958 milliseconds Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand status took 958 milliseconds Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 01 11:53:42 volumio volumio[946]: info: Command Router : Notfying DB Updatefalse Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 01 11:53:42 volumio volumio[946]: info: ControllerMpd::pushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::servicePushState Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 11:53:42 volumio volumio[946]: verbose: CURRENT POSITION 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: No code Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: ControllerMpd::pushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::servicePushState Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 11:53:42 volumio volumio[946]: verbose: CURRENT POSITION 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: No code Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: ------------------------------ 1098ms Oct 01 11:53:42 volumio volumio[946]: info: ------------------------------ 1097ms Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand rescan Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 01 11:53:42 volumio volumio[946]: info: Oct 01 11:53:42 volumio volumio[946]: ---------------------------- MPD announces state update: update Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand rescan took 4 milliseconds Oct 01 11:53:42 volumio volumio[946]: info: ControllerMpd::getState Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds Oct 01 11:53:42 volumio volumio[946]: info: sendMpdCommand status took 2 milliseconds Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 01 11:53:42 volumio volumio[946]: info: Command Router : Notfying DB Updatetrue Oct 01 11:53:42 volumio volumio[946]: verbose: ControllerMpd::parseState Oct 01 11:53:42 volumio volumio[946]: info: ControllerMpd::pushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::servicePushState Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 01 11:53:42 volumio volumio[946]: verbose: CURRENT POSITION 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: No code Oct 01 11:53:42 volumio volumio[946]: info: CoreStateMachine::pushState Oct 01 11:53:42 volumio volumio[946]: info: CorePlayQueue::getTrack 0 Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::volumioPushState Oct 01 11:53:42 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 11:53:42 volumio volumio[946]: info: ------------------------------ 53ms Oct 01 11:53:43 volumio volumio[946]: info: CoreCommandRouter::Close All Modals sent Oct 01 11:53:44 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 11:53:48 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 11:53:52 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 11:53:56 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 11:54:10 volumio volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 11:54:10 volumio volumio[946]: { Error: connect ETIMEDOUT 199.59.149.234:443 Oct 01 11:54:10 volumio volumio[946]: at Object._errnoException (util.js:1022:11) Oct 01 11:54:10 volumio volumio[946]: at _exceptionWithHostPort (util.js:1044:20) Oct 01 11:54:10 volumio volumio[946]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 01 11:54:10 volumio volumio[946]: code: 'ETIMEDOUT', Oct 01 11:54:10 volumio volumio[946]: errno: 'ETIMEDOUT', Oct 01 11:54:10 volumio volumio[946]: syscall: 'connect', Oct 01 11:54:10 volumio volumio[946]: address: '199.59.149.234', Oct 01 11:54:10 volumio volumio[946]: port: 443 } Oct 01 11:54:10 volumio volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 11:54:11 volumio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 11:53 Oct 01 11:54:11 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"