-- Logs begin at Tue 2024-01-23 17:43:15 UTC, end at Sat 2024-08-24 17:28:56 UTC. -- Aug 24 17:28:24 volumio systemd[1]: Time has been changed Aug 24 17:28:24 volumio volumio[949]: info: Applying required configuration parameters for plugin 80s80s Aug 24 17:28:24 volumio volumio[949]: info: [1724520504635] [80s80s] API delay: 30 Aug 24 17:28:24 volumio volumio[949]: info: Loading plugin "spop"... Aug 24 17:28:24 volumio volumio[949]: Starting albumart workers Aug 24 17:28:24 volumio volumio[949]: Starting albumart workers Aug 24 17:28:24 volumio volumio[949]: Starting albumart workers Aug 24 17:28:26 volumio volumio[949]: info: Loading plugin "volspotconnect2"... Aug 24 17:28:27 volumio volumio[949]: info: ___________ START PLUGINS ___________ Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 17:28:27 volumio volumio[949]: info: [1724520507261] CoreMusicLibrary::Adding element Media Servers Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:27 volumio volumio[949]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 17:28:27 volumio volumio[949]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 17:28:27 volumio volumio[949]: info: [1724520507344] CoreMusicLibrary::Adding element Last_100 Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 17:28:27 volumio volumio[949]: info: [1724520507348] CoreMusicLibrary::Adding element Webradio Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 24 17:28:27 volumio volumio[949]: info: Youtube::onStart Adding to browse sources Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 17:28:27 volumio volumio[949]: info: [1724520507359] CoreMusicLibrary::Adding element Youtube Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 24 17:28:27 volumio volumio[949]: info: [1724520507373] CoreMusicLibrary::Adding element 80s80s Radio Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:27 volumio volumio[949]: [SpotifyConnect] Creating VLS config file Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:27 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:27 volumio volumio[949]: [SpotifyConnect] Starting metadata listener Aug 24 17:28:27 volumio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Aug 24 17:28:27 volumio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:27 volumio volumio[949]: info: ------------------------------------------- Aug 24 17:28:27 volumio volumio[949]: info: ----- MyVolumio plugins startup ---- Aug 24 17:28:27 volumio volumio[949]: info: ------------------------------------------- Aug 24 17:28:27 volumio volumio[949]: info: Loading plugins from folder /myvolumio/plugins Aug 24 17:28:27 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Aug 24 17:28:27 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Aug 24 17:28:27 volumio systemd[1]: Starting Spotify Daemon... Aug 24 17:28:27 volumio systemd[1]: Started Spotify Daemon. Aug 24 17:28:27 volumio sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 24 17:28:27 volumio sudo[1018]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:27 volumio volumio[949]: info: Loading plugins from folder /data/myvolumio/plugins Aug 24 17:28:27 volumio volumio[949]: info: Loading plugin "cd_controller"... Aug 24 17:28:27 volumio sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:27 volumio systemd[1]: Starting Volspotconnect2 Daemon... Aug 24 17:28:27 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 24 17:28:27 volumio sudo[1024]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:27 volumio volumio[949]: info: Loading plugin "streaming_services"... Aug 24 17:28:27 volumio volumio[1025]: 2024-08-24 17:28:27 [SPTF] 17:28:27.711 I [offline_authorizer.cpp:297] Unable to login offline: no such user Aug 24 17:28:27 volumio volumio[1025]: 2024-08-24 17:28:27 [SPTF] 17:28:27.712 I [ap:1752] Connecting to AP ap.spotify.com:4070 Aug 24 17:28:27 volumio volumio[1033]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Aug 24 17:28:27 volumio volumio[1033]: Reading Config from "volspotify.toml" Aug 24 17:28:27 volumio volumio[1033]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Aug 24 17:28:27 volumio volumio[1025]: 2024-08-24 17:28:27 [SPTF] 17:28:27.778 I [ap:1226] Connected to AP: 34.158.0.131:4070 Aug 24 17:28:27 volumio volumio[949]: info: Loading plugin "my_volumio"... Aug 24 17:28:27 volumio volumio[1033]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Aug 24 17:28:28 volumio volumio[1025]: 2024-08-24 17:28:28 [SPTF] 17:28:28.004 E [ap:3953] Connection error: 410 Aug 24 17:28:28 volumio volumio[1025]: 2024-08-24 17:28:28 [WARN] Login failed: Bad username and/or password Aug 24 17:28:28 volumio volumio[1025]: 2024-08-24 17:28:28 [ERR ] Could not get the playlist container. Aug 24 17:28:28 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Aug 24 17:28:28 volumio systemd[1]: Unit spop.service entered failed state. Aug 24 17:28:28 volumio volumio[949]: info: Adding CD REST API Endpoints Aug 24 17:28:28 volumio volumio[949]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Aug 24 17:28:28 volumio volumio[949]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Aug 24 17:28:28 volumio volumio[949]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Aug 24 17:28:28 volumio volumio[949]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 24 17:28:28 volumio volumio[949]: info: Detecting CD presence Aug 24 17:28:28 volumio volumio[949]: info: Streaming services startup Aug 24 17:28:28 volumio volumio[949]: info: Starting Streaming Daemon Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: Starting MyVolumio Remote Streaming Endpoints Aug 24 17:28:28 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 24 17:28:28 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:28 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 24 17:28:28 volumio volumio[949]: info: Loading i18n strings for locale it Aug 24 17:28:28 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 24 17:28:28 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:28 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 24 17:28:28 volumio volumio[949]: Updating browse sources language Aug 24 17:28:28 volumio volumio[949]: Cannot find translation for sourceMedia Servers Aug 24 17:28:28 volumio volumio[949]: Cannot find translation for sourceYoutube Aug 24 17:28:28 volumio volumio[949]: Cannot find translation for source80s80s Radio Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:28 volumio volumio-streaming-daemon[1048]: ############################ Aug 24 17:28:28 volumio volumio-streaming-daemon[1048]: # Volumio Streaming Daemon # Aug 24 17:28:28 volumio volumio-streaming-daemon[1048]: # Running on port 7777 # Aug 24 17:28:28 volumio volumio-streaming-daemon[1048]: ############################ Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::initPlayerControls Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:28:28 volumio volumio[949]: info: BOOT COMPLETED Aug 24 17:28:28 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:28 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 24 17:28:31 volumio ifup[494]: dhcpcd[516]: timed out Aug 24 17:28:31 volumio ifup[494]: dhcpcd[516]: allowing 8 seconds for IPv4LL timeout Aug 24 17:28:31 volumio dhcpcd[516]: timed out Aug 24 17:28:31 volumio dhcpcd[516]: allowing 8 seconds for IPv4LL timeout Aug 24 17:28:31 volumio volumio-remote-updater[563]: [2024-08-24 17:28:31] [connect] Successful connection Aug 24 17:28:33 volumio volumio[949]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::Close All Modals sent Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::Close All Modals sent Aug 24 17:28:34 volumio volumio[949]: Express server listening on port 3000 Aug 24 17:28:34 volumio volumio[949]: Volumio Calling Home Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::resetVolumioState Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::getcurrentVolume Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::volumioRetrievevolume Aug 24 17:28:34 volumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:34 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:34 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:34 volumio volumio[949]: info: Setting Device type: Raspberry PI Aug 24 17:28:34 volumio volumio[949]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 24 17:28:34 volumio volumio[949]: SpotifyConnect: 6963.366ms Aug 24 17:28:34 volumio volumio[949]: info: SpopD Daemon Started Aug 24 17:28:34 volumio volumio[949]: info: Data script executed Aug 24 17:28:34 volumio volumio[949]: info: MPD running with PID590 ,establishing connection Aug 24 17:28:34 volumio volumio-remote-updater[563]: [2024-08-24 17:28:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724520511 101 Aug 24 17:28:34 volumio volumio[949]: info: VolumeController:: Volume=45 Mute =false Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::pushState Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:34 volumio volumio[949]: info: CorePlayQueue::getTrack 0 Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::volumioPushState Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:28:34 volumio volumio[949]: info: Reloading queue from file Aug 24 17:28:34 volumio volumio[949]: info: Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::setRepeat null single undefined Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::pushState Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:34 volumio volumio[949]: info: CorePlayQueue::getTrack 0 Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::volumioPushState Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::setRandom true Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::pushState Aug 24 17:28:34 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:34 volumio volumio[949]: info: CorePlayQueue::getTrack 0 Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::volumioPushState Aug 24 17:28:34 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:28:34 volumio volumio[949]: info: mDNS: A device disapperared from network Aug 24 17:28:34 volumio volumio[949]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 24 17:28:34 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 24 17:28:34 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:34 volumio sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 24 17:28:34 volumio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:34 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 24 17:28:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 24 17:28:34 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:34 volumio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 24 17:28:34 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 24 17:28:34 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:34 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:34 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 24 17:28:35 volumio shairport-sync[1101]: Successful Startup Aug 24 17:28:35 volumio volumio[949]: Discovery: adding 6230d29b-5e8d-41fa-b317-b70863064c12 Aug 24 17:28:35 volumio volumio[949]: info: mDNS: Found device Volumio Aug 24 17:28:35 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 24 17:28:35 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 24 17:28:35 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 24 17:28:35 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:35 volumio shairport-sync[1110]: Successful Startup Aug 24 17:28:35 volumio volumio[949]: error: MyVolumio Custom Token format not valid, refreshing it Aug 24 17:28:35 volumio sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:35 volumio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:35 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:35 volumio volumio[949]: info: Upmpdcli Daemon Started Aug 24 17:28:35 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 24 17:28:35 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 24 17:28:35 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 24 17:28:35 volumio volumio[949]: info: Shairport-Sync Started Aug 24 17:28:35 volumio volumio[949]: Error adding Membership: Error: addMembership EINVAL Aug 24 17:28:35 volumio volumio[949]: info: Shairport-Sync Started Aug 24 17:28:35 volumio volumio[949]: info: CoreCommandRouter::volumioGetState Aug 24 17:28:35 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:35 volumio volumio[949]: info: CorePlayQueue::getTrack 0 Aug 24 17:28:35 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:35 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:35 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:35 volumio volumio[949]: Discovery: this is already registered, 6230d29b-5e8d-41fa-b317-b70863064c12 Aug 24 17:28:35 volumio volumio[949]: info: mDNS: Found device Volumio Aug 24 17:28:35 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:35 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:35 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:35 volumio sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 24 17:28:35 volumio sudo[1170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:35 volumio sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:35 volumio sudo[1168]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:35 volumio sudo[1170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:35 volumio sudo[1170]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::volumioGetVisibleSources Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::volumioGetState Aug 24 17:28:36 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:36 volumio volumio[949]: info: CorePlayQueue::getTrack 0 Aug 24 17:28:36 volumio sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:36 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 24 17:28:36 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 24 17:28:36 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 24 17:28:36 volumio volumio[949]: info: Listing playlists Aug 24 17:28:36 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:36 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:36 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:36 volumio volumio[949]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 24 17:28:36 volumio volumio[949]: info: MYVOLUMIO: Adding device Aug 24 17:28:36 volumio volumio[949]: info: MYVOLUMIO: Evaluating Server Aug 24 17:28:37 volumio volumio[949]: info: Fetching Streaming Services browse cache Aug 24 17:28:37 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 24 17:28:37 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:37 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 24 17:28:37 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:38 volumio volumio[949]: info: MyVolumio status changed Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:38 volumio volumio[949]: info: Streaming services startup Aug 24 17:28:38 volumio volumio[949]: info: Re-Starting Streaming Daemon Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Aug 24 17:28:38 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:38 volumio volumio[949]: info: Removing browser output: myVolumio user plan is not superstar Aug 24 17:28:38 volumio volumio[949]: info: Removing audio output: Aug 24 17:28:38 volumio volumio[949]: info: Stoppping Tunnel 1 Aug 24 17:28:38 volumio sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 24 17:28:38 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:39 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 24 17:28:39 volumio volumio-streaming-daemon[1048]: Restarting daemon Aug 24 17:28:39 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:39 volumio volumio[949]: info: Remote SSH Stopped Aug 24 17:28:39 volumio volumio[949]: info: Setting Geolocation for MyVolumio to eu4 Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:39 volumio volumio[949]: info: Connecting to daemon Aug 24 17:28:39 volumio volumio[949]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 24 17:28:39 volumio volumio[949]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:39 volumio ifup[494]: dhcpcd[516]: timed out Aug 24 17:28:39 volumio dhcpcd[516]: timed out Aug 24 17:28:39 volumio dhcpcd[516]: exited Aug 24 17:28:39 volumio ifup[494]: dhcpcd[516]: exited Aug 24 17:28:39 volumio ifup[494]: Failed to bring up eth0. Aug 24 17:28:39 volumio volumio[949]: info: Fetching Streaming Services browse cache Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 24 17:28:39 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 24 17:28:40 volumio volumio[949]: info: Updating MyVolumio device info Aug 24 17:28:40 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:40 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:40 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:28:41 volumio volumio[949]: info: CoreCommandRouter::volumioGetQueue Aug 24 17:28:41 volumio volumio[949]: info: CoreStateMachine::getQueue Aug 24 17:28:41 volumio volumio[949]: info: CorePlayQueue::getQueue Aug 24 17:28:44 volumio volumio[949]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.599&uuid=e46969d9e559eeb773bcf8e602a0b193" http://updates.volumio.org:7070/downloader-v1/track-device Aug 24 17:28:44 volumio volumio[949]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 24 17:28:44 volumio volumio[949]: Dload Upload Total Spent Left Speed Aug 24 17:28:44 volumio volumio[949]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 24 17:28:44 volumio volumio[949]: retrying in 5 seconds, trial 0 Aug 24 17:28:44 volumio volumio[949]: Volumio Calling Home Aug 24 17:28:53 volumio volumio[949]: info: CoreCommandRouter::volumioPlay Aug 24 17:28:53 volumio volumio[949]: UNSET VOLATILE Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::play index 10 Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::stop Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 17:28:53 volumio volumio[949]: UNSET VOLATILE Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::play index undefined Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 17:28:53 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::startPlaybackTimer Aug 24 17:28:53 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:53 volumio volumio[949]: info: [1724520533617] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 24 17:28:53 volumio volumio[949]: info: [1724520533619] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Aug 24 17:28:53 volumio volumio[949]: info: [1724520533919] [80s80s] received new event containing 2 songs. Aug 24 17:28:53 volumio volumio[949]: info: ControllerMpd::sendMpdCommand stop Aug 24 17:28:53 volumio volumio[949]: info: sending command... Aug 24 17:28:53 volumio volumio[949]: info: parsing response... Aug 24 17:28:53 volumio volumio[949]: info: ControllerMpd::sendMpdCommand clear Aug 24 17:28:53 volumio volumio[949]: info: sending command... Aug 24 17:28:53 volumio volumio[949]: info: Aug 24 17:28:53 volumio volumio[949]: ---------------------------- MPD announces system playlist update Aug 24 17:28:53 volumio volumio[949]: info: Ignoring MPD Status Update Aug 24 17:28:53 volumio volumio[949]: info: parsing response... Aug 24 17:28:53 volumio volumio[949]: info: [1724520533932] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 24 17:28:53 volumio volumio[949]: info: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Aug 24 17:28:53 volumio volumio[949]: info: sending command... Aug 24 17:28:53 volumio volumio[949]: info: Aug 24 17:28:53 volumio volumio[949]: info: Aug 24 17:28:53 volumio volumio[949]: ---------------------------- MPD announces system playlist update Aug 24 17:28:53 volumio volumio[949]: info: Ignoring MPD Status Update Aug 24 17:28:53 volumio volumio[949]: info: ------------------------------ 9ms Aug 24 17:28:53 volumio volumio[949]: info: parsing response... Aug 24 17:28:53 volumio volumio[949]: info: ControllerMpd::sendMpdCommand play Aug 24 17:28:53 volumio volumio[949]: info: sending command... Aug 24 17:28:53 volumio volumio[949]: info: ------------------------------ 9ms Aug 24 17:28:53 volumio volumio[949]: info: parsing response... Aug 24 17:28:53 volumio volumio[949]: info: [1724520533947] [80s80s] Pushing the next song state: New Order - Bizarre Love Triangle Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:53 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:53 volumio volumio[949]: info: CoreCommandRouter::servicePushState Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::syncState Aug 24 17:28:53 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:53 volumio volumio[949]: info: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music/v4/63/a3/f8/63a3f8b5-c4c3-d702-c21d-867a33e7c712/source/600x600bb.jpg","name":"New Order - Bizarre Love Triangle","title":"Bizarre Love Triangle","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"223","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 24 17:28:53 volumio volumio[949]: info: CURRENT POSITION 10 Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::syncState stateService play Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::syncState currentStatus stop Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::pushState Aug 24 17:28:53 volumio volumio[949]: info: CoreStateMachine::getState Aug 24 17:28:53 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:53 volumio volumio[949]: info: CoreCommandRouter::volumioPushState Aug 24 17:28:53 volumio volumio[949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:28:53 volumio volumio[949]: info: [1724520533987] [80s80s] getContentOfUrl started with url http://ntp-a4.nict.go.jp/cgi-bin/json Aug 24 17:28:53 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:28:54 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:28:54 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Aug 24 17:28:54 volumio volumio[949]: info: CorePlayQueue::getTrack 10 Aug 24 17:28:54 volumio volumio[949]: info: CorePlayQueue::getTrack 22 Aug 24 17:28:54 volumio volumio[949]: info: ERROR increasePlaybackTimer remainingTime:-197 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 24 17:28:54 volumio volumio[949]: info: ERROR increasePlaybackTimer remainingTime:-449 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 24 17:28:54 volumio volumio[949]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.599&uuid=e46969d9e559eeb773bcf8e602a0b193" http://updates.volumio.org:7070/downloader-v1/track-device Aug 24 17:28:54 volumio volumio[949]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 24 17:28:54 volumio volumio[949]: Dload Upload Total Spent Left Speed Aug 24 17:28:54 volumio volumio[949]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 24 17:28:54 volumio volumio[949]: retrying in 5 seconds, trial 1 Aug 24 17:28:54 volumio volumio[949]: Volumio Calling Home Aug 24 17:28:54 volumio volumio[949]: info: ERROR increasePlaybackTimer remainingTime:-699 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 24 17:28:55 volumio volumio[949]: info: [1724520535052] [80s80s] Failed to query api, status code: 404 Aug 24 17:28:55 volumio volumio[949]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 17:28:55 volumio volumio[949]: ReferenceError: station is not defined Aug 24 17:28:55 volumio volumio[949]: at ClientRequest.http.get (/data/plugins/music_service/80s80s/index.js:478:26) Aug 24 17:28:55 volumio volumio[949]: at Object.onceWrapper (events.js:315:30) Aug 24 17:28:55 volumio volumio[949]: at emitOne (events.js:116:13) Aug 24 17:28:55 volumio volumio[949]: at ClientRequest.emit (events.js:211:7) Aug 24 17:28:55 volumio volumio[949]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Aug 24 17:28:55 volumio volumio[949]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Aug 24 17:28:55 volumio volumio[949]: at Socket.socketOnData (_http_client.js:440:20) Aug 24 17:28:55 volumio volumio[949]: at emitOne (events.js:116:13) Aug 24 17:28:55 volumio volumio[949]: at Socket.emit (events.js:211:7) Aug 24 17:28:55 volumio volumio[949]: at addChunk (_stream_readable.js:263:12) Aug 24 17:28:55 volumio volumio[949]: at readableAddChunk (_stream_readable.js:250:11) Aug 24 17:28:55 volumio volumio[949]: at Socket.Readable.push (_stream_readable.js:208:10) Aug 24 17:28:55 volumio volumio[949]: at TCP.onread (net.js:607:20) Aug 24 17:28:55 volumio volumio[949]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 17:28:55 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 24 17:28:56 volumio sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 17:27 Aug 24 17:28:56 volumio sudo[1384]: 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="f1d44ae5428081b5b8ca49f419b134ef83e234df" VOLUMIO_FE_VERSION="021732c5252c1ad4fb85955339e1f032fe4ae9f0" VOLUMIO_BE_VERSION="caf6c0f9a8e3cd984391aa77621b50a9247127b8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Aug 2 17:17:17 CEST 2019" VOLUMIO_VERSION="2.599" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c28fac21d698202aa4e6e014f85b1eaf"