-- Logs begin at Tue 2024-01-23 17:43:15 UTC, end at Fri 2024-08-23 17:09:15 UTC. -- Aug 23 17:08:00 volumio systemd[1]: Time has been changed Aug 23 17:08:00 volumio volumio[965]: info: Applying required configuration parameters for plugin 80s80s Aug 23 17:08:00 volumio volumio[965]: info: [1724432880537] [80s80s] API delay: 30 Aug 23 17:08:00 volumio volumio[965]: info: Loading plugin "spop"... Aug 23 17:08:00 volumio volumio[965]: Starting albumart workers Aug 23 17:08:00 volumio volumio[965]: Starting albumart workers Aug 23 17:08:00 volumio volumio[965]: Starting albumart workers Aug 23 17:08:01 volumio volumio-remote-updater[569]: [2024-08-23 17:08:01] [connect] Successful connection Aug 23 17:08:02 volumio volumio[965]: info: Loading plugin "volspotconnect2"... Aug 23 17:08:03 volumio volumio[965]: info: ___________ START PLUGINS ___________ Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 17:08:03 volumio volumio[965]: info: [1724432883183] CoreMusicLibrary::Adding element Media Servers Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:03 volumio volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 17:08:03 volumio volumio[965]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 17:08:03 volumio volumio[965]: info: [1724432883264] CoreMusicLibrary::Adding element Last_100 Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 17:08:03 volumio volumio[965]: info: [1724432883268] CoreMusicLibrary::Adding element Webradio Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 17:08:03 volumio volumio[965]: info: Youtube::onStart Adding to browse sources Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 17:08:03 volumio volumio[965]: info: [1724432883279] CoreMusicLibrary::Adding element Youtube Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 23 17:08:03 volumio volumio[965]: info: [1724432883293] CoreMusicLibrary::Adding element 80s80s Radio Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:03 volumio volumio[965]: [SpotifyConnect] Creating VLS config file Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:03 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:03 volumio volumio[965]: [SpotifyConnect] Starting metadata listener Aug 23 17:08:03 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Aug 23 17:08:03 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:03 volumio volumio[965]: info: ------------------------------------------- Aug 23 17:08:03 volumio volumio[965]: info: ----- MyVolumio plugins startup ---- Aug 23 17:08:03 volumio volumio[965]: info: ------------------------------------------- Aug 23 17:08:03 volumio volumio[965]: info: Loading plugins from folder /myvolumio/plugins Aug 23 17:08:03 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Aug 23 17:08:03 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Aug 23 17:08:03 volumio systemd[1]: Starting Spotify Daemon... Aug 23 17:08:03 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 23 17:08:03 volumio systemd[1]: Started Spotify Daemon. Aug 23 17:08:03 volumio volumio[965]: info: Loading plugins from folder /data/myvolumio/plugins Aug 23 17:08:03 volumio volumio[965]: info: Loading plugin "cd_controller"... Aug 23 17:08:03 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:03 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:03 volumio systemd[1]: Starting Volspotconnect2 Daemon... Aug 23 17:08:03 volumio systemd[1]: Started Volspotconnect2 Daemon. Aug 23 17:08:03 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:03 volumio volumio[965]: info: Loading plugin "streaming_services"... Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [SPTF] 17:08:03.631 I [offline_authorizer.cpp:297] Unable to login offline: no such user Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [SPTF] 17:08:03.631 I [ap:1752] Connecting to AP ap.spotify.com:4070 Aug 23 17:08:03 volumio volumio[1049]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Aug 23 17:08:03 volumio volumio[1049]: Reading Config from "volspotify.toml" Aug 23 17:08:03 volumio volumio[1049]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [SPTF] 17:08:03.725 I [ap:1226] Connected to AP: 34.158.0.131:4070 Aug 23 17:08:03 volumio volumio[965]: info: Loading plugin "my_volumio"... Aug 23 17:08:03 volumio volumio[1049]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [SPTF] 17:08:03.920 E [ap:3953] Connection error: 410 Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [WARN] Login failed: Bad username and/or password Aug 23 17:08:03 volumio volumio[1041]: 2024-08-23 17:08:03 [ERR ] Could not get the playlist container. Aug 23 17:08:03 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Aug 23 17:08:03 volumio systemd[1]: Unit spop.service entered failed state. Aug 23 17:08:04 volumio volumio[965]: info: Adding CD REST API Endpoints Aug 23 17:08:04 volumio volumio[965]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Aug 23 17:08:04 volumio volumio[965]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Aug 23 17:08:04 volumio volumio[965]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Aug 23 17:08:04 volumio volumio[965]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 23 17:08:04 volumio volumio[965]: info: Detecting CD presence Aug 23 17:08:04 volumio volumio[965]: info: Streaming services startup Aug 23 17:08:04 volumio volumio[965]: info: Starting Streaming Daemon Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: Starting MyVolumio Remote Streaming Endpoints Aug 23 17:08:04 volumio sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 23 17:08:04 volumio sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:04 volumio systemd[1]: Stopping Volumio Streaming Daemon... Aug 23 17:08:04 volumio volumio[965]: info: Loading i18n strings for locale it Aug 23 17:08:04 volumio volumio[965]: Updating browse sources language Aug 23 17:08:04 volumio volumio[965]: Cannot find translation for sourceMedia Servers Aug 23 17:08:04 volumio volumio[965]: Cannot find translation for sourceYoutube Aug 23 17:08:04 volumio volumio[965]: Cannot find translation for source80s80s Radio Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:04 volumio systemd[1]: Starting Volumio Streaming Daemon... Aug 23 17:08:04 volumio systemd[1]: Started Volumio Streaming Daemon. Aug 23 17:08:04 volumio sudo[1058]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:04 volumio volumio-streaming-daemon[1064]: ############################ Aug 23 17:08:04 volumio volumio-streaming-daemon[1064]: # Volumio Streaming Daemon # Aug 23 17:08:04 volumio volumio-streaming-daemon[1064]: # Running on port 7777 # Aug 23 17:08:04 volumio volumio-streaming-daemon[1064]: ############################ Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::initPlayerControls Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 17:08:04 volumio volumio[965]: info: BOOT COMPLETED Aug 23 17:08:04 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:04 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 23 17:08:05 volumio dhcpcd[522]: timed out Aug 23 17:08:05 volumio dhcpcd[522]: allowing 8 seconds for IPv4LL timeout Aug 23 17:08:05 volumio ifup[500]: dhcpcd[522]: timed out Aug 23 17:08:05 volumio ifup[500]: dhcpcd[522]: allowing 8 seconds for IPv4LL timeout Aug 23 17:08:09 volumio volumio[965]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 23 17:08:09 volumio volumio[965]: info: CoreCommandRouter::Close All Modals sent Aug 23 17:08:09 volumio volumio[965]: info: CoreCommandRouter::Close All Modals sent Aug 23 17:08:09 volumio volumio[965]: Express server listening on port 3000 Aug 23 17:08:09 volumio volumio[965]: Volumio Calling Home Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::resetVolumioState Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::getcurrentVolume Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::volumioRetrievevolume Aug 23 17:08:10 volumio sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:10 volumio sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:10 volumio sudo[1086]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:10 volumio volumio[965]: info: Setting Device type: Raspberry PI Aug 23 17:08:10 volumio volumio[965]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 23 17:08:10 volumio volumio[965]: SpotifyConnect: 6945.066ms Aug 23 17:08:10 volumio volumio[965]: info: SpopD Daemon Started Aug 23 17:08:10 volumio volumio[965]: info: Data script executed Aug 23 17:08:10 volumio volumio[965]: info: MPD running with PID608 ,establishing connection Aug 23 17:08:10 volumio volumio[965]: info: VolumeController:: Volume=45 Mute =false Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:10 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:10 volumio volumio[965]: info: Reloading queue from file Aug 23 17:08:10 volumio volumio[965]: info: Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::setRepeat null single undefined Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:10 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::setRandom true Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:10 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:10 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:10 volumio volumio[965]: info: mDNS: A device disapperared from network Aug 23 17:08:10 volumio volumio[965]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 23 17:08:10 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 23 17:08:10 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:10 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 23 17:08:10 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 23 17:08:10 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 23 17:08:10 volumio sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 23 17:08:10 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:10 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:10 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:10 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 23 17:08:10 volumio volumio[965]: Discovery: adding 6230d29b-5e8d-41fa-b317-b70863064c12 Aug 23 17:08:10 volumio volumio[965]: info: mDNS: Found device Volumio Aug 23 17:08:10 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 23 17:08:10 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:10 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Aug 23 17:08:10 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Aug 23 17:08:10 volumio systemd[1]: Started ShairportSync AirTunes receiver. Aug 23 17:08:10 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:10 volumio shairport-sync[1125]: Successful Startup Aug 23 17:08:10 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:10 volumio volumio[965]: error: MyVolumio Custom Token format not valid, refreshing it Aug 23 17:08:10 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:10 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 23 17:08:11 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 23 17:08:11 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 23 17:08:11 volumio volumio[965]: info: Shairport-Sync Started Aug 23 17:08:11 volumio volumio[965]: Error adding Membership: Error: addMembership EINVAL Aug 23 17:08:11 volumio volumio[965]: info: Shairport-Sync Started Aug 23 17:08:11 volumio volumio[965]: info: Upmpdcli Daemon Started Aug 23 17:08:11 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Aug 23 17:08:11 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:11 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:11 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:11 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:11 volumio volumio[965]: Discovery: this is already registered, 6230d29b-5e8d-41fa-b317-b70863064c12 Aug 23 17:08:11 volumio volumio[965]: info: mDNS: Found device Volumio Aug 23 17:08:11 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:11 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:11 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:11 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:11 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:08:11 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:11 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:11 volumio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:11 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:11 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Aug 23 17:08:12 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:12 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:12 volumio sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:12 volumio sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:12 volumio sudo[1201]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 23 17:08:12 volumio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 23 17:08:12 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 23 17:08:12 volumio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:12 volumio volumio[965]: info: Listing playlists Aug 23 17:08:12 volumio sudo[1207]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:12 volumio volumio[965]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 23 17:08:12 volumio volumio[965]: info: MYVOLUMIO: Adding device Aug 23 17:08:12 volumio volumio[965]: info: MYVOLUMIO: Evaluating Server Aug 23 17:08:12 volumio volumio[965]: info: Fetching Streaming Services browse cache Aug 23 17:08:13 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 23 17:08:13 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:13 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 23 17:08:13 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:13 volumio dhcpcd[522]: timed out Aug 23 17:08:13 volumio dhcpcd[522]: exited Aug 23 17:08:13 volumio ifup[500]: dhcpcd[522]: timed out Aug 23 17:08:13 volumio ifup[500]: dhcpcd[522]: exited Aug 23 17:08:13 volumio ifup[500]: Failed to bring up eth0. Aug 23 17:08:14 volumio volumio[965]: info: MyVolumio status changed Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:14 volumio volumio[965]: info: Streaming services startup Aug 23 17:08:14 volumio volumio[965]: info: Re-Starting Streaming Daemon Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:14 volumio volumio[965]: info: Removing browser output: myVolumio user plan is not superstar Aug 23 17:08:14 volumio volumio[965]: info: Removing audio output: Aug 23 17:08:14 volumio volumio[965]: info: Stoppping Tunnel 1 Aug 23 17:08:14 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 23 17:08:14 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:14 volumio volumio-streaming-daemon[1064]: Restarting daemon Aug 23 17:08:14 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 23 17:08:14 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:14 volumio volumio[965]: info: Remote SSH Stopped Aug 23 17:08:14 volumio volumio[965]: info: Setting Geolocation for MyVolumio to eu4 Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:14 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:15 volumio volumio[965]: info: Fetching Streaming Services browse cache Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:15 volumio volumio[965]: info: Connecting to daemon Aug 23 17:08:15 volumio volumio[965]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 23 17:08:15 volumio volumio[965]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:08:15 volumio volumio[965]: info: Updating MyVolumio device info Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:15 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:16 volumio volumio-remote-updater[569]: [2024-08-23 17:08:16] [connect] Successful connection Aug 23 17:08:16 volumio volumio-remote-updater[569]: [2024-08-23 17:08:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1724432896 101 Aug 23 17:08:17 volumio volumio[965]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:08:17 volumio volumio[965]: info: CoreStateMachine::getQueue Aug 23 17:08:17 volumio volumio[965]: info: CorePlayQueue::getQueue Aug 23 17:08:17 volumio volumio[965]: info: No input data Aug 23 17:08:17 volumio volumio[965]: info: No input data Aug 23 17:08:20 volumio volumio[965]: 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 23 17:08:20 volumio volumio[965]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 23 17:08:20 volumio volumio[965]: Dload Upload Total Spent Left Speed Aug 23 17:08:20 volumio volumio[965]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 23 17:08:20 volumio volumio[965]: retrying in 5 seconds, trial 0 Aug 23 17:08:20 volumio volumio[965]: Volumio Calling Home Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::volumioPlay Aug 23 17:08:29 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::play index 2 Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::stop Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:29 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::play index undefined Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:29 volumio volumio[965]: info: CorePlayQueue::getTrack 2 Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:08:29 volumio volumio[965]: info: CorePlayQueue::getTrack 2 Aug 23 17:08:29 volumio volumio[965]: info: [1724432909041] ControllerWebradio::clearAddPlayTrack Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clear Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: Aug 23 17:08:29 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:29 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99500214" Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: Aug 23 17:08:29 volumio volumio[965]: info: ------------------------------ 7ms Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand play Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: Aug 23 17:08:29 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:29 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: ------------------------------ 5ms Aug 23 17:08:29 volumio volumio[965]: info: Aug 23 17:08:29 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clearerror Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:29 volumio volumio[965]: info: sending command... Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: parsing response... Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:29 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:29 volumio volumio[965]: info: CorePlayQueue::getTrack 2 Aug 23 17:08:29 volumio volumio[965]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"256.mp3","artist":"(#1 - 32/1000) 80s Forever - We Keep The 80s Alive","album":null,"uri":"https://s2.ssl-stream.com:8050/256.mp3","trackType":"mp3"} Aug 23 17:08:29 volumio volumio[965]: info: CURRENT POSITION 2 Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::syncState stateService stop Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:29 volumio sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:29 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:29 volumio volumio[965]: info: No code Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:29 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:29 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:29 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:29 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:29 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:29 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:29 volumio volumio[965]: info: ------------------------------ 108ms Aug 23 17:08:29 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:29 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:29 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:30 volumio volumio[965]: 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 23 17:08:30 volumio volumio[965]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 23 17:08:30 volumio volumio[965]: Dload Upload Total Spent Left Speed Aug 23 17:08:30 volumio volumio[965]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 23 17:08:30 volumio volumio[965]: retrying in 5 seconds, trial 1 Aug 23 17:08:30 volumio volumio[965]: Volumio Calling Home Aug 23 17:08:33 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 17:08:33 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 23 17:08:33 volumio volumio[965]: info: CoreCommandRouter::volumioPlay Aug 23 17:08:33 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::play index 0 Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::stop Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:33 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::play index undefined Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:33 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:33 volumio volumio[965]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:08:33 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:33 volumio volumio[965]: info: [1724432913938] ControllerWebradio::clearAddPlayTrack Aug 23 17:08:33 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:08:33 volumio volumio[965]: info: sending command... Aug 23 17:08:33 volumio volumio[965]: info: parsing response... Aug 23 17:08:33 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clear Aug 23 17:08:33 volumio volumio[965]: info: sending command... Aug 23 17:08:33 volumio volumio[965]: info: Aug 23 17:08:33 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:33 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:33 volumio volumio[965]: info: parsing response... Aug 23 17:08:33 volumio volumio[965]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=157531" Aug 23 17:08:33 volumio volumio[965]: info: sending command... Aug 23 17:08:33 volumio volumio[965]: info: Aug 23 17:08:33 volumio volumio[965]: info: ------------------------------ 5ms Aug 23 17:08:34 volumio volumio[965]: info: parsing response... Aug 23 17:08:34 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 23 17:08:34 volumio volumio[965]: info: ControllerMpd::sendMpdCommand play Aug 23 17:08:34 volumio volumio[965]: info: sending command... Aug 23 17:08:34 volumio volumio[965]: info: Aug 23 17:08:34 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:34 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:34 volumio volumio[965]: info: parsing response... Aug 23 17:08:34 volumio volumio[965]: info: ------------------------------ 3ms Aug 23 17:08:35 volumio volumio[965]: info: Aug 23 17:08:35 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:35 volumio volumio[965]: info: sending command... Aug 23 17:08:35 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 23 17:08:35 volumio volumio[965]: info: parsing response... Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:35 volumio volumio[965]: info: sending command... Aug 23 17:08:35 volumio volumio[965]: info: Aug 23 17:08:35 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:35 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:35 volumio volumio[965]: info: Aug 23 17:08:35 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:35 volumio volumio[965]: info: parsing response... Aug 23 17:08:35 volumio volumio[965]: info: sending command... Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:35 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:35 volumio volumio[965]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"pitertm - To Jest Weekend z Radiem Italo4you","artist":"Radio Italo4you","album":null,"uri":"http://91.232.4.33:8018/stream","trackType":""} Aug 23 17:08:35 volumio volumio[965]: info: CURRENT POSITION 0 Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:35 volumio volumio[965]: info: ------------------------------ 49ms Aug 23 17:08:35 volumio volumio[965]: info: ------------------------------ 33ms Aug 23 17:08:35 volumio volumio[965]: info: parsing response... Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:35 volumio volumio[965]: info: sending command... Aug 23 17:08:35 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:35 volumio volumio[965]: info: parsing response... Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:35 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:35 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:35 volumio volumio[965]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"pitertm - To Jest Weekend z Radiem Italo4you","artist":"Radio Italo4you","album":null,"uri":"http://91.232.4.33:8018/stream","trackType":""} Aug 23 17:08:35 volumio volumio[965]: info: CURRENT POSITION 0 Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:08:35 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:08:35 volumio volumio[965]: info: Received an update from plugin. extracting info from payload Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:35 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:35 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:35 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:35 volumio sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:35 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:35 volumio volumio[965]: info: ------------------------------ 108ms Aug 23 17:08:35 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:35 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:35 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:35 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:40 volumio volumio[965]: 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 23 17:08:40 volumio volumio[965]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 23 17:08:40 volumio volumio[965]: Dload Upload Total Spent Left Speed Aug 23 17:08:40 volumio volumio[965]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 23 17:08:40 volumio volumio[965]: retrying in 5 seconds, trial 2 Aug 23 17:08:40 volumio volumio[965]: Volumio Calling Home Aug 23 17:08:41 volumio volumio[965]: info: VolumeController::SetAlsaVolume50 Aug 23 17:08:41 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:41 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:41 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:41 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:41 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:41 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:41 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:41 volumio volumio[965]: info: VolumeController::SetAlsaVolume55 Aug 23 17:08:41 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:41 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:41 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:41 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:41 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:41 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:41 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:44 volumio volumio[965]: info: VolumeController::SetAlsaVolume65 Aug 23 17:08:44 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:44 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:44 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:44 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:44 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:44 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:44 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:57 volumio volumio[965]: info: CoreCommandRouter::volumioPlay Aug 23 17:08:57 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::play index 5 Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::stop Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:57 volumio volumio[965]: UNSET VOLATILE Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::updateTrackBlock Aug 23 17:08:57 volumio volumio[965]: info: CorePlayQueue::getTrackBlock Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:57 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:57 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:57 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::serviceStop Aug 23 17:08:57 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Aug 23 17:08:57 volumio volumio[965]: info: CoreCommandRouter::serviceStop Aug 23 17:08:57 volumio volumio[965]: info: [1724432937441] ControllerWebradio::stop Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:08:57 volumio sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:57 volumio sudo[1656]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::play index undefined Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:08:57 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:08:57 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:08:57 volumio volumio[965]: info: [1724432937478] ControllerWebradio::clearAddPlayTrack Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: Aug 23 17:08:57 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clear Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: Aug 23 17:08:57 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:57 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519374" Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: Aug 23 17:08:57 volumio volumio[965]: info: ------------------------------ 9ms Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::pushError Aug 23 17:08:57 volumio volumio[965]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Aug 23 17:08:57 volumio volumio[965]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:291:54) Aug 23 17:08:57 volumio volumio[965]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 23 17:08:57 volumio volumio[965]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 23 17:08:57 volumio volumio[965]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 23 17:08:57 volumio volumio[965]: info: ------------------------------ 28ms Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 23 17:08:57 volumio volumio[965]: info: ControllerMpd::sendMpdCommand play Aug 23 17:08:57 volumio volumio[965]: info: sending command... Aug 23 17:08:57 volumio volumio[965]: info: Aug 23 17:08:57 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:57 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:57 volumio volumio[965]: info: parsing response... Aug 23 17:08:57 volumio volumio[965]: info: ------------------------------ 3ms Aug 23 17:08:58 volumio volumio[965]: info: Aug 23 17:08:58 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 23 17:08:58 volumio volumio[965]: info: Aug 23 17:08:58 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio volumio[965]: info: Aug 23 17:08:58 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:08:58 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:08:58 volumio volumio[965]: info: Aug 23 17:08:58 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: ------------------------------ 7ms Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:58 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:08:58 volumio volumio[965]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jennifer Rush - The Power Of Love - 1984","artist":"100 GREATEST OF THE 80'S","album":null,"uri":"https://maggie.torontocast.com:8064/stream","trackType":""} Aug 23 17:08:58 volumio volumio[965]: info: CURRENT POSITION 5 Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:58 volumio volumio[965]: info: sending command... Aug 23 17:08:58 volumio volumio[965]: info: ------------------------------ 63ms Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: parsing response... Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:08:58 volumio sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:58 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:08:58 volumio volumio[965]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jennifer Rush - The Power Of Love - 1984","artist":"100 GREATEST OF THE 80'S","album":null,"uri":"https://maggie.torontocast.com:8064/stream","trackType":""} Aug 23 17:08:58 volumio volumio[965]: info: CURRENT POSITION 5 Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:08:58 volumio volumio[965]: info: Received an update from plugin. extracting info from payload Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:58 volumio sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:58 volumio sudo[1673]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:58 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:58 volumio volumio[965]: info: ControllerMpd::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:08:58 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:08:58 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:08:58 volumio volumio[965]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Jennifer Rush - The Power Of Love - 1984","artist":"100 GREATEST OF THE 80'S","album":null,"uri":"https://maggie.torontocast.com:8064/stream","trackType":""} Aug 23 17:08:58 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:58 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:58 volumio volumio[965]: info: CURRENT POSITION 5 Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:08:58 volumio volumio[965]: info: Received an update from plugin. extracting info from payload Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:58 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:58 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:08:58 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:08:58 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:08:58 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:58 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:58 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:08:58 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Aug 23 17:08:58 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:08:58 volumio volumio[965]: info: ------------------------------ 193ms Aug 23 17:08:58 volumio volumio[965]: info: ------------------------------ 185ms Aug 23 17:08:58 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root Aug 23 17:09:05 volumio volumio[965]: info: CoreCommandRouter::volumioPlay Aug 23 17:09:05 volumio volumio[965]: UNSET VOLATILE Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::play index 8 Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::stop Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:05 volumio volumio[965]: UNSET VOLATILE Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::updateTrackBlock Aug 23 17:09:05 volumio volumio[965]: info: CorePlayQueue::getTrackBlock Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:09:05 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:09:05 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:09:05 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::serviceStop Aug 23 17:09:05 volumio volumio[965]: info: CorePlayQueue::getTrack 5 Aug 23 17:09:05 volumio volumio[965]: info: CoreCommandRouter::serviceStop Aug 23 17:09:05 volumio volumio[965]: info: [1724432945097] ControllerWebradio::stop Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:09:05 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:09:05 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::play index undefined Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:05 volumio volumio[965]: info: CorePlayQueue::getTrack 8 Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:09:05 volumio volumio[965]: info: CorePlayQueue::getTrack 8 Aug 23 17:09:05 volumio volumio[965]: info: [1724432945209] ControllerWebradio::clearAddPlayTrack Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: Aug 23 17:09:05 volumio volumio[965]: ---------------------------- MPD announces state update: player Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::getState Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand status Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clear Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: Aug 23 17:09:05 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:09:05 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::parseState Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99589302" Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: Aug 23 17:09:05 volumio volumio[965]: info: ------------------------------ 14ms Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::parseTrackInfo Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::pushError Aug 23 17:09:05 volumio volumio[965]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Aug 23 17:09:05 volumio volumio[965]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:291:54) Aug 23 17:09:05 volumio volumio[965]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 23 17:09:05 volumio volumio[965]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Aug 23 17:09:05 volumio volumio[965]: at process._tickCallback (internal/process/next_tick.js:180:9) Aug 23 17:09:05 volumio volumio[965]: info: ------------------------------ 27ms Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:05 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 23 17:09:05 volumio volumio[965]: info: ControllerMpd::sendMpdCommand play Aug 23 17:09:05 volumio volumio[965]: info: sending command... Aug 23 17:09:05 volumio volumio[965]: info: parsing response... Aug 23 17:09:13 volumio volumio[965]: info: CoreCommandRouter::volumioPlay Aug 23 17:09:13 volumio volumio[965]: UNSET VOLATILE Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::play index 10 Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::stop Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:13 volumio volumio[965]: UNSET VOLATILE Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::play index undefined Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: [1724432953047] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 23 17:09:13 volumio volumio[965]: info: [1724432953048] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Aug 23 17:09:13 volumio volumio[965]: info: [1724432953325] [80s80s] received new event containing 2 songs. Aug 23 17:09:13 volumio volumio[965]: info: ControllerMpd::sendMpdCommand stop Aug 23 17:09:13 volumio volumio[965]: info: sending command... Aug 23 17:09:13 volumio volumio[965]: info: parsing response... Aug 23 17:09:13 volumio volumio[965]: info: ControllerMpd::sendMpdCommand clear Aug 23 17:09:13 volumio volumio[965]: info: sending command... Aug 23 17:09:13 volumio volumio[965]: info: Aug 23 17:09:13 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:09:13 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:09:13 volumio volumio[965]: info: parsing response... Aug 23 17:09:13 volumio volumio[965]: info: [1724432953335] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Aug 23 17:09:13 volumio volumio[965]: info: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Aug 23 17:09:13 volumio volumio[965]: info: sending command... Aug 23 17:09:13 volumio volumio[965]: info: Aug 23 17:09:13 volumio volumio[965]: info: Aug 23 17:09:13 volumio volumio[965]: ---------------------------- MPD announces system playlist update Aug 23 17:09:13 volumio volumio[965]: info: Ignoring MPD Status Update Aug 23 17:09:13 volumio volumio[965]: info: ------------------------------ 7ms Aug 23 17:09:13 volumio volumio[965]: info: parsing response... Aug 23 17:09:13 volumio volumio[965]: info: ControllerMpd::sendMpdCommand play Aug 23 17:09:13 volumio volumio[965]: info: sending command... Aug 23 17:09:13 volumio volumio[965]: info: ------------------------------ 8ms Aug 23 17:09:13 volumio volumio[965]: info: parsing response... Aug 23 17:09:13 volumio volumio[965]: info: [1724432953349] [80s80s] Pushing the next song state: Thompson Twins - Hold Me Now Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: CoreCommandRouter::servicePushState Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::syncState Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music125/v4/29/f8/28/29f82819-8748-7731-62e8-ec96648fa56f/source/600x600bb.jpg","name":"Thompson Twins - Hold Me Now","title":"Hold Me Now","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"273","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 23 17:09:13 volumio volumio[965]: info: CURRENT POSITION 10 Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::syncState stateService play Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::pushState Aug 23 17:09:13 volumio volumio[965]: info: CoreStateMachine::getState Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: CoreCommandRouter::volumioPushState Aug 23 17:09:13 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:09:13 volumio volumio[965]: info: [1724432953381] [80s80s] getContentOfUrl started with url http://ntp-a4.nict.go.jp/cgi-bin/json Aug 23 17:09:13 volumio sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:09:13 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:09:13 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 10 Aug 23 17:09:13 volumio volumio[965]: info: CorePlayQueue::getTrack 79 Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-97 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-100 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-101 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-113 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-171 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-349 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-351 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-352 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:13 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-364 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-422 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-599 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-602 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-603 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-614 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-672 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-850 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-854 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: ERROR increasePlaybackTimer remainingTime:-854 negative - askedForPrefetch:true - simulateStopStartDone:false Aug 23 17:09:14 volumio volumio[965]: info: [1724432954481] [80s80s] Failed to query api, status code: 404 Aug 23 17:09:14 volumio volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 17:09:14 volumio volumio[965]: ReferenceError: station is not defined Aug 23 17:09:14 volumio volumio[965]: at ClientRequest.http.get (/data/plugins/music_service/80s80s/index.js:478:26) Aug 23 17:09:14 volumio volumio[965]: at Object.onceWrapper (events.js:315:30) Aug 23 17:09:14 volumio volumio[965]: at emitOne (events.js:116:13) Aug 23 17:09:14 volumio volumio[965]: at ClientRequest.emit (events.js:211:7) Aug 23 17:09:14 volumio volumio[965]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:551:21) Aug 23 17:09:14 volumio volumio[965]: at HTTPParser.parserOnHeadersComplete (_http_common.js:115:23) Aug 23 17:09:14 volumio volumio[965]: at Socket.socketOnData (_http_client.js:440:20) Aug 23 17:09:14 volumio volumio[965]: at emitOne (events.js:116:13) Aug 23 17:09:14 volumio volumio[965]: at Socket.emit (events.js:211:7) Aug 23 17:09:14 volumio volumio[965]: at addChunk (_stream_readable.js:263:12) Aug 23 17:09:14 volumio volumio[965]: at readableAddChunk (_stream_readable.js:250:11) Aug 23 17:09:14 volumio volumio[965]: at Socket.Readable.push (_stream_readable.js:208:10) Aug 23 17:09:14 volumio volumio[965]: at TCP.onread (net.js:607:20) Aug 23 17:09:14 volumio volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 17:09:14 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 23 17:09:15 volumio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 17:08 Aug 23 17:09:15 volumio sudo[1843]: 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"