-- Logs begin at Thu 2019-02-14 10:12:00 WET, end at Mon 2024-08-19 09:54:03 WEST. -- Aug 19 09:53:00 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:00 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetBrowseSources Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: STREAMING PROXY: Starting server on port 3245 Aug 19 09:53:03 volumio-3-pi-zero-2 volumio[838]: Node JS runtime: 14 Aug 19 09:53:04 volumio-3-pi-zero-2 systemd[1]: apt-daily.service: Succeeded. Aug 19 09:53:04 volumio-3-pi-zero-2 systemd[1]: Started Daily apt download activities. Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: MyVolumio token set successfully Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: MYVOLUMIO: Adding device Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: MYVOLUMIO: Evaluating Server Aug 19 09:53:05 volumio-3-pi-zero-2 systemd[1]: Starting Daily apt upgrade and clean activities... Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Discovery: A device disappeared from network Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Device volumio disappeared from network Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Disconnected from remote: 192.168.1.127 Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Discovery: A device disappeared from network Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: MyVolumio status changed Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Streaming services startup Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Starting Streaming Daemon Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Removing browser output: myVolumio user plan is not superstar Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Removing audio output: Aug 19 09:53:05 volumio-3-pi-zero-2 volumio[838]: info: Stoppping Tunnel 1 Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1754]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1754]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: error: Cannot start Volumio Streaming Daemon Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: adding fb48476e-ae55-4751-b1bf-2daaa1db1b7e Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Found device Volumio 3 pi zero 2 Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: adding 0702d4e2-5c19-4836-ab64-cea2a3943b79 Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Found device Volumio Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Connecting to remote: 192.168.1.127 Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Connected to remote: 192.168.1.127 Aug 19 09:53:06 volumio-3-pi-zero-2 sudo[1757]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:06 volumio-3-pi-zero-2 volumio[838]: info: Remote SSH Stopped Aug 19 09:53:07 volumio-3-pi-zero-2 sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 09:53:07 volumio-3-pi-zero-2 sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:07 volumio-3-pi-zero-2 sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 09:53:07 volumio-3-pi-zero-2 sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:07 volumio-3-pi-zero-2 sudo[1766]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:08 volumio-3-pi-zero-2 sudo[1764]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:08 volumio-3-pi-zero-2 volumio[838]: verbose: New Socket.io Connection to 192.168.1.157 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 19 09:53:09 volumio-3-pi-zero-2 systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 19 09:53:09 volumio-3-pi-zero-2 systemd[1]: Started Daily apt upgrade and clean activities. Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1803]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:09 volumio-3-pi-zero-2 sudo[1801]: pam_unix(sudo:session): session closed for user root Aug 19 09:53:09 volumio-3-pi-zero-2 volumio[838]: verbose: New Socket.io Connection to 192.168.1.157 from 192.168.1.182 UA: Mozilla/5.0 (Windows NT 6.1; Win64; x64; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: Listing playlists Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: Setting Geolocation for MyVolumio to eu8 Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:10 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:10 volumio-3-pi-zero-2 systemd[1]: man-db.service: Succeeded. Aug 19 09:53:10 volumio-3-pi-zero-2 systemd[1]: Started Daily man-db regeneration. Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: info: Updating MyVolumio device info Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:11 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Info Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Getting this device information Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 09:53:12 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: Completed starting MyVolumio Plugin Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: BOOT COMPLETED Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: [Metrics] CommandRouter: 77s 761.12ms Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumiosetStartupVolume Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: VolumeController:: Setting startup Volume 30 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: VolumeController::SetAlsaVolume30 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::Close All Modals sent Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::Close All Modals sent Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: ---------------------------- MPD announces state update: mixer Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::getState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: ControllerMpd::sendMpdCommand status Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: ---------------------------- MPD announces state update: mixer Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::getState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: ControllerMpd::sendMpdCommand status Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: ---------------------------- MPD announces state update: mixer Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::getState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: ControllerMpd::sendMpdCommand status Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: sendMpdCommand status took 22 milliseconds Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: ControllerMpd::parseState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::servicePushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: 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} Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: CURRENT POSITION 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState stateService stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState currentStatus stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: No code Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ------------------------------ 149ms Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: sendMpdCommand status took 138 milliseconds Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: sendMpdCommand status took 137 milliseconds Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: ControllerMpd::parseState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::servicePushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: 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} Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: CURRENT POSITION 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState stateService stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState currentStatus stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: No code Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: ControllerMpd::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::servicePushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: 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} Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: verbose: CURRENT POSITION 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState stateService stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::syncState currentStatus stop Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: No code Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::pushState Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:13 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioPushState Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: info: ------------------------------ 294ms Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: info: ------------------------------ 294ms Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 19 09:53:14 volumio-3-pi-zero-2 volumio[838]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 19 09:53:15 volumio-3-pi-zero-2 volumio[838]: info: MYVOLUMIO: Adding device Aug 19 09:53:15 volumio-3-pi-zero-2 volumio[838]: info: MYVOLUMIO: Evaluating Server Aug 19 09:53:15 volumio-3-pi-zero-2 volumio[838]: info: Setting Geolocation for MyVolumio to eu3 Aug 19 09:53:15 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Info Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Getting this device information Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: Listing playlists Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: Updating MyVolumio device info Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:16 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 19 09:53:17 volumio-3-pi-zero-2 volumio[838]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Version Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Info Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Getting this device information Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:27 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Version Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: Received Get System Info Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: Discovery: Getting this device information Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetState Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getTrack 0 Aug 19 09:53:33 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 19 09:53:40 volumio-3-pi-zero-2 volumiologrotate[522]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Aug 19 09:53:40 volumio-3-pi-zero-2 volumiologrotate[522]: ls: cannot access '3': No such file or directory Aug 19 09:53:40 volumio-3-pi-zero-2 volumiologrotate[522]: ls: cannot access 'PI': No such file or directory Aug 19 09:53:40 volumio-3-pi-zero-2 volumiologrotate[522]: ls: cannot access 'ZERO': No such file or directory Aug 19 09:53:40 volumio-3-pi-zero-2 volumiologrotate[522]: ls: cannot access '2': No such file or directory Aug 19 09:53:43 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 09:53:43 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::volumioGetQueue Aug 19 09:53:43 volumio-3-pi-zero-2 volumio[838]: info: CoreStateMachine::getQueue Aug 19 09:53:43 volumio-3-pi-zero-2 volumio[838]: info: CorePlayQueue::getQueue Aug 19 09:53:43 volumio-3-pi-zero-2 volumio[838]: info: Preload queue cleared Aug 19 09:53:50 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Aug 19 09:53:50 volumio-3-pi-zero-2 volumio[838]: info: Preload queue cleared Aug 19 09:53:51 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 09:53:51 volumio-3-pi-zero-2 volumio[838]: info: Preload queue cleared Aug 19 09:53:55 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 09:53:55 volumio-3-pi-zero-2 volumio[838]: info: Preload queue cleared Aug 19 09:53:59 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 09:53:59 volumio-3-pi-zero-2 volumio[838]: info: Preload queue cleared Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: TypeError: Cannot read property 'length' of undefined Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at Parser.emit (events.js:315:20) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at IncomingMessage.emit (events.js:327:22) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 19 09:54:01 volumio-3-pi-zero-2 volumio[838]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 19 09:54:03 volumio-3-pi-zero-2 sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-19 09:53 Aug 19 09:54:03 volumio-3-pi-zero-2 sudo[1908]: 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"