-- Logs begin at Thu 2022-04-21 07:28:35 UTC, end at Sun 2025-04-20 09:19:27 UTC. -- Apr 20 09:18:14 loja-olhao systemd[1]: Time has been changed Apr 20 09:18:15 loja-olhao wpa_supplicant[944]: wlan0: WPA: Key negotiation completed with 98:35:ed:c9:f8:d8 [PTK=CCMP GTK=TKIP] Apr 20 09:18:15 loja-olhao wpa_supplicant[944]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:35:ed:c9:f8:d8 completed [id=0 id_str=] Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: carrier acquired Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: rebinding lease of 192.168.1.198 Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: leased 192.168.1.198 for 3600 seconds Apr 20 09:18:15 loja-olhao avahi-daemon[656]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.198. Apr 20 09:18:15 loja-olhao dhcpcd[955]: eth0: adding route to 10.161.2.0/24 Apr 20 09:18:15 loja-olhao dhcpcd[955]: eth0: adding default route via 10.161.2.254 Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: adding host route to 192.168.1.198 via 127.0.0.1 Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: adding route to 192.168.1.0/24 Apr 20 09:18:15 loja-olhao dhcpcd[955]: wlan0: adding default route via 192.168.1.1 Apr 20 09:18:15 loja-olhao avahi-daemon[656]: New relevant interface wlan0.IPv4 for mDNS. Apr 20 09:18:15 loja-olhao avahi-daemon[656]: Registering new address record for 192.168.1.198 on wlan0.IPv4. Apr 20 09:18:15 loja-olhao dhcpcd[955]: eth0: removing route to 10.161.2.0/24 Apr 20 09:18:15 loja-olhao dhcpcd[955]: eth0: removing default route via 10.161.2.254 Apr 20 09:18:15 loja-olhao wireless.js[747]: trying... Apr 20 09:18:15 loja-olhao sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 09:18:15 loja-olhao sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:15 loja-olhao sudo[1064]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:15 loja-olhao wireless.js[747]: Connected to: ----Vodafone-C9F8CD Apr 20 09:18:15 loja-olhao wireless.js[747]: ---- Apr 20 09:18:15 loja-olhao sudo[1073]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:15 loja-olhao sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:15 loja-olhao sudo[1073]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:15 loja-olhao wireless.js[747]: ... joined AP, wlan0 IPv4 is 192.168.1.198, ipV6 is undefined Apr 20 09:18:15 loja-olhao wireless.js[747]: It's done! AP Apr 20 09:18:15 loja-olhao systemd[1]: Started Wireless Services. Apr 20 09:18:15 loja-olhao systemd[1]: Starting Volumio Backend Module... Apr 20 09:18:15 loja-olhao systemd[1]: Started Volumio Backend Module. Apr 20 09:18:15 loja-olhao systemd[1]: Starting Volumio Streaming Daemon... Apr 20 09:18:15 loja-olhao systemd[1]: Started Volumio Streaming Daemon. Apr 20 09:18:15 loja-olhao systemd[1]: Starting Multi-User System. Apr 20 09:18:15 loja-olhao systemd[1]: Reached target Multi-User System. Apr 20 09:18:15 loja-olhao systemd[1]: Starting Graphical Interface. Apr 20 09:18:15 loja-olhao systemd[1]: Reached target Graphical Interface. Apr 20 09:18:15 loja-olhao systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 20 09:18:15 loja-olhao systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 20 09:18:15 loja-olhao systemd[1]: Startup finished in 9.128s (kernel) + 20.874s (userspace) = 30.002s. Apr 20 09:18:15 loja-olhao volumio-streaming-daemon[1083]: ############################ Apr 20 09:18:15 loja-olhao volumio-streaming-daemon[1083]: # Volumio Streaming Daemon # Apr 20 09:18:15 loja-olhao volumio-streaming-daemon[1083]: # Running on port 7777 # Apr 20 09:18:15 loja-olhao volumio-streaming-daemon[1083]: ############################ Apr 20 09:18:16 loja-olhao ntpd[734]: Listen normally on 4 wlan0 192.168.1.198 UDP 123 Apr 20 09:18:16 loja-olhao ntpd[734]: peers refreshed Apr 20 09:18:16 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:16] [info] asio async_connect error: system:111 (Connection refused) Apr 20 09:18:16 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 09:18:16 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:16] [error] handle_connect error: Underlying Transport Error Apr 20 09:18:18 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ----- Volumio2 ---- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ----- System startup ---- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:18 loja-olhao volumio[1082]: info: MYVOLUMIO Environment detected Apr 20 09:18:18 loja-olhao volumio[1082]: info: Plugin folders cleanup Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning into folder /volumio/app/plugins/ Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category audio_interface Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category miscellanea Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category music_service Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category plugins.json Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category system_controller Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category user_interface Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning into folder /data/plugins/ Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category miscellanea Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category music_service Apr 20 09:18:18 loja-olhao volumio[1082]: info: Scanning category system_controller Apr 20 09:18:18 loja-olhao volumio[1082]: info: Plugin folders cleanup completed Apr 20 09:18:18 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ----- Core plugins startup ---- Apr 20 09:18:18 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:18 loja-olhao volumio[1082]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 09:18:18 loja-olhao volumio[1082]: info: Adding plugin upnp to MyMusic Plugins Apr 20 09:18:18 loja-olhao volumio[1082]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 09:18:18 loja-olhao volumio[1082]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 09:18:18 loja-olhao volumio[1082]: info: Loading plugins from folder /data/plugins/ Apr 20 09:18:18 loja-olhao volumio[1082]: info: Loading plugin "system"... Apr 20 09:18:18 loja-olhao volumio[1082]: info: Loading plugin "ampswitch"... Apr 20 09:18:19 loja-olhao volumio[1082]: info: Applying required configuration parameters for plugin ampswitch Apr 20 09:18:19 loja-olhao volumio[1082]: info: Loading plugin "appearance"... Apr 20 09:18:20 loja-olhao volumio[1082]: info: Loading plugin "network"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "services"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "alsa_controller"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "wizard"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "volumio_command_line_client"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:21 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:21 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:21 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "upnp"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: [1745140701547] Starting Upmpd Daemon Apr 20 09:18:21 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "my_music"... Apr 20 09:18:21 loja-olhao volumio[1082]: info: Loading plugin "mpd"... Apr 20 09:18:22 loja-olhao volumio[1082]: info: Loading plugin "upnp_browser"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "networkfs"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "alarm-clock"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "airplay_emulation"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: Starting Shairport Sync Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "last_100"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "webradio"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "i2s_dacs"... Apr 20 09:18:23 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:18:23 loja-olhao volumio[1082]: info: Loading plugin "volumiodiscovery"... Apr 20 09:18:23 loja-olhao volumio[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 09:18:23 loja-olhao node[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 09:18:23 loja-olhao volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:18:23 loja-olhao node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:18:23 loja-olhao volumio[1082]: *** WARNING *** For more information see Apr 20 09:18:23 loja-olhao node[1082]: *** WARNING *** For more information see Apr 20 09:18:23 loja-olhao volumio[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 09:18:23 loja-olhao node[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 09:18:23 loja-olhao volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:18:23 loja-olhao node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 09:18:24 loja-olhao volumio[1082]: *** WARNING *** For more information see Apr 20 09:18:24 loja-olhao node[1082]: *** WARNING *** For more information see Apr 20 09:18:24 loja-olhao volumio[1082]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 09:18:24 loja-olhao volumio[1082]: Discovery: StartAdv! undefined Apr 20 09:18:24 loja-olhao volumio[1082]: Discovery: Started advertising... loja-olhao - undefined Apr 20 09:18:24 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "auto_play"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "autostart"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Applying required configuration parameters for plugin autostart Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "outputs"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "albumart"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Plugin example_plugin is not enabled Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "inputs"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "updater_comm"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Plugin mpdemulation is not enabled Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "rest_api"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "websocket"... Apr 20 09:18:24 loja-olhao volumio[1082]: info: Loading plugin "onedrive_music_library"... Apr 20 09:18:24 loja-olhao volumio[1082]: Forking 3 albumart workers Apr 20 09:18:25 loja-olhao volumio[1082]: Starting albumart workers Apr 20 09:18:25 loja-olhao volumio[1082]: Starting albumart workers Apr 20 09:18:25 loja-olhao volumio[1082]: Starting albumart workers Apr 20 09:18:26 loja-olhao volumio[1082]: info: Loading plugin "spop"... Apr 20 09:18:26 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:26] [connect] Successful connection Apr 20 09:18:27 loja-olhao volumio[1082]: info: Applying required configuration parameters for plugin spop Apr 20 09:18:27 loja-olhao volumio[1082]: info: Loading plugin "tunein_radio"... Apr 20 09:18:28 loja-olhao volumio[1082]: info: Applying required configuration parameters for plugin tunein_radio Apr 20 09:18:28 loja-olhao volumio[1082]: info: Loading plugin "volspotconnect2"... Apr 20 09:18:29 loja-olhao volumio[1082]: info: Loading plugin "backup_restore"... Apr 20 09:18:29 loja-olhao volumio[1082]: info: ___________ START PLUGINS ___________ Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:18:29 loja-olhao volumio[1082]: info: [1745140709794] CoreMusicLibrary::Adding element Media Servers Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:29 loja-olhao volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 09:18:29 loja-olhao volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:18:29 loja-olhao volumio[1082]: info: [1745140709882] CoreMusicLibrary::Adding element Last_100 Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:18:29 loja-olhao volumio[1082]: info: [1745140709886] CoreMusicLibrary::Adding element Webradio Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:18:29 loja-olhao volumio[1082]: info: [1745140709904] CoreMusicLibrary::Adding element Onedrive Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:29 loja-olhao volumio[1082]: info: TuneIn addToBrowseSources Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 09:18:29 loja-olhao volumio[1082]: info: [1745140709924] CoreMusicLibrary::Adding element TuneIn Radio Apr 20 09:18:29 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:29 loja-olhao sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Apr 20 09:18:29 loja-olhao volumio[1082]: info: $ref=$, _isPromise=true, _successFn=undefined, _failFn=undefined, $ref=$, _boundArgs=null, _hasContext=false, _nextContext=undefined, _currentContext=undefined Apr 20 09:18:29 loja-olhao sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:29 loja-olhao systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Apr 20 09:18:29 loja-olhao systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Apr 20 09:18:29 loja-olhao systemd[1]: Starting Spotify Daemon... Apr 20 09:18:29 loja-olhao systemd[1]: Started Spotify Daemon. Apr 20 09:18:29 loja-olhao sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:29 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:29 loja-olhao volumio[1082]: info: ----- MyVolumio plugins startup ---- Apr 20 09:18:29 loja-olhao volumio[1082]: info: ------------------------------------------- Apr 20 09:18:29 loja-olhao volumio[1082]: info: Loading plugins from folder /myvolumio/plugins Apr 20 09:18:29 loja-olhao sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 20 09:18:29 loja-olhao sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:30 loja-olhao systemd[1]: Starting Volspotconnect2 Daemon... Apr 20 09:18:30 loja-olhao systemd[1]: Started Volspotconnect2 Daemon. Apr 20 09:18:30 loja-olhao sudo[1161]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:30 loja-olhao volumio[1082]: info: Loading plugins from folder /data/myvolumio/plugins Apr 20 09:18:30 loja-olhao volumio[1082]: info: Loading plugin "cd_controller"... Apr 20 09:18:30 loja-olhao volumio[1082]: info: Loading plugin "streaming_services"... Apr 20 09:18:30 loja-olhao volumio[1169]: vollibrespot v0.1.7 624ccec 2019-07-30 (librespot c9f83e7 2019-07-30) -- Built On 2019-07-30 Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [SPTF] 09:18:30.371 I [offline_authorizer.cpp:297] Unable to login offline: no such user Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [SPTF] 09:18:30.371 I [ap:1752] Connecting to AP ap.spotify.com:4070 Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [SPTF] 09:18:30.455 I [ap:1226] Connected to AP: 104.199.65.9:4070 Apr 20 09:18:30 loja-olhao volumio[1169]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 20 09:18:30 loja-olhao volumio[1082]: info: Loading plugin "my_volumio"... Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [SPTF] 09:18:30.687 E [ap:3953] Connection error: 410 Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [WARN] Login failed: Bad username and/or password Apr 20 09:18:30 loja-olhao volumio[1160]: 2025-04-20 09:18:30 [ERR ] Could not get the playlist container. Apr 20 09:18:30 loja-olhao systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Apr 20 09:18:30 loja-olhao systemd[1]: Unit spop.service entered failed state. Apr 20 09:18:31 loja-olhao volumio[1082]: info: Adding CD REST API Endpoints Apr 20 09:18:31 loja-olhao volumio[1082]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Apr 20 09:18:31 loja-olhao volumio[1082]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Apr 20 09:18:31 loja-olhao volumio[1082]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Apr 20 09:18:31 loja-olhao volumio[1082]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 20 09:18:31 loja-olhao volumio[1082]: info: Detecting CD presence Apr 20 09:18:31 loja-olhao volumio[1082]: info: Streaming services startup Apr 20 09:18:31 loja-olhao volumio[1082]: info: Starting Streaming Daemon Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: Starting MyVolumio Remote Streaming Endpoints Apr 20 09:18:31 loja-olhao sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 20 09:18:31 loja-olhao sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:31 loja-olhao systemd[1]: Stopping Volumio Streaming Daemon... Apr 20 09:18:31 loja-olhao volumio[1082]: info: Loading i18n strings for locale pt Apr 20 09:18:31 loja-olhao systemd[1]: Starting Volumio Streaming Daemon... Apr 20 09:18:31 loja-olhao systemd[1]: Started Volumio Streaming Daemon. Apr 20 09:18:31 loja-olhao sudo[1181]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:31 loja-olhao volumio-streaming-daemon[1187]: ############################ Apr 20 09:18:31 loja-olhao volumio-streaming-daemon[1187]: # Volumio Streaming Daemon # Apr 20 09:18:31 loja-olhao volumio-streaming-daemon[1187]: # Running on port 7777 # Apr 20 09:18:31 loja-olhao volumio-streaming-daemon[1187]: ############################ Apr 20 09:18:31 loja-olhao volumio[1082]: Updating browse sources language Apr 20 09:18:31 loja-olhao volumio[1082]: Cannot find translation for sourceMedia Servers Apr 20 09:18:31 loja-olhao volumio[1082]: Cannot find translation for sourceOnedrive Apr 20 09:18:31 loja-olhao volumio[1082]: Cannot find translation for sourceTuneIn Radio Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::initPlayerControls Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 09:18:31 loja-olhao volumio[1082]: info: BOOT COMPLETED Apr 20 09:18:31 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:18:36 loja-olhao volumio[1082]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 20 09:18:36 loja-olhao volumio[1082]: info: CoreCommandRouter::Close All Modals sent Apr 20 09:18:36 loja-olhao volumio[1082]: info: CoreCommandRouter::Close All Modals sent Apr 20 09:18:36 loja-olhao volumio[1082]: Express server listening on port 3000 Apr 20 09:18:36 loja-olhao volumio[1082]: Volumio Calling Home Apr 20 09:18:36 loja-olhao volumio[1082]: info: CoreStateMachine::resetVolumioState Apr 20 09:18:36 loja-olhao volumio[1082]: info: CoreStateMachine::getcurrentVolume Apr 20 09:18:36 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 09:18:36 loja-olhao sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:36 loja-olhao sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:36 loja-olhao sudo[1211]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:36 loja-olhao volumio[1082]: info: Setting Device type: Raspberry PI Apr 20 09:18:36 loja-olhao volumio[1082]: [SpotifyConnect] Vollibrespot Daemon Started Apr 20 09:18:36 loja-olhao volumio[1082]: [SpotifyConnect] Starting metadata listener Apr 20 09:18:36 loja-olhao volumio[1082]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Apr 20 09:18:36 loja-olhao volumio[1082]: info: SpopD Daemon Started Apr 20 09:18:36 loja-olhao volumio[1082]: info: MPD running with PID668 ,establishing connection Apr 20 09:18:37 loja-olhao volumio[1082]: info: VolumeController:: Volume=60 Mute =false Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:37 loja-olhao volumio[1082]: info: Apr 20 09:18:37 loja-olhao volumio[1082]: ---------------------------- Client requests Volume 70 Apr 20 09:18:37 loja-olhao volumio[1082]: info: VolumeController::SetAlsaVolume70 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 09:18:37 loja-olhao volumio[1082]: info: Reloading queue from file Apr 20 09:18:37 loja-olhao volumio[1082]: info: Apr 20 09:18:37 loja-olhao volumio[1082]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Apr 20 09:18:37 loja-olhao volumio[1082]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Apr 20 09:18:37 loja-olhao volumio[1082]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Apr 20 09:18:37 loja-olhao volumio[1082]: at emitOne (events.js:116:13) Apr 20 09:18:37 loja-olhao volumio[1082]: at Socket.emit (events.js:211:7) Apr 20 09:18:37 loja-olhao volumio[1082]: at addChunk (_stream_readable.js:263:12) Apr 20 09:18:37 loja-olhao volumio[1082]: at readableAddChunk (_stream_readable.js:246:13) Apr 20 09:18:37 loja-olhao volumio[1082]: at Socket.Readable.push (_stream_readable.js:208:10) Apr 20 09:18:37 loja-olhao volumio[1082]: at Pipe.onread (net.js:607:20) Apr 20 09:18:37 loja-olhao volumio[1082]: info: Setting volume on startup at 70 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::setRepeat null single undefined Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::setRandom null Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:37 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: local Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: music Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: talk Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: sports Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: location Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: language Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushing element: podcast Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Pushed elements: 7 Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: local Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: music Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: talk Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: sports Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: location Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: language Apr 20 09:18:37 loja-olhao volumio[1082]: info: [TuneIn] Element: podcast Apr 20 09:18:37 loja-olhao volumio[1082]: error: MyVolumio Custom Token format not valid, refreshing it Apr 20 09:18:37 loja-olhao sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Apr 20 09:18:37 loja-olhao sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:37 loja-olhao sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Apr 20 09:18:37 loja-olhao sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:37 loja-olhao systemd[1]: Starting ShairportSync AirTunes receiver... Apr 20 09:18:37 loja-olhao sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 09:18:37 loja-olhao systemd[1]: Started ShairportSync AirTunes receiver. Apr 20 09:18:37 loja-olhao sudo[1226]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:37 loja-olhao systemd[1]: Stopping ShairportSync AirTunes receiver... Apr 20 09:18:37 loja-olhao sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:37 loja-olhao systemd[1]: Starting ShairportSync AirTunes receiver... Apr 20 09:18:37 loja-olhao systemd[1]: Started ShairportSync AirTunes receiver. Apr 20 09:18:37 loja-olhao sudo[1228]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:37 loja-olhao systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 20 09:18:37 loja-olhao shairport-sync[1248]: Successful Startup Apr 20 09:18:37 loja-olhao systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 09:18:37 loja-olhao sudo[1235]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:37 loja-olhao volumio[1082]: Discovery: adding ce3dfd72-6001-4b38-9a86-4dd8136adeee Apr 20 09:18:37 loja-olhao volumio[1082]: info: mDNS: Found device loja-olhao Apr 20 09:18:37 loja-olhao sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:37 loja-olhao sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:37 loja-olhao sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Apr 20 09:18:37 loja-olhao volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 20 09:18:38 loja-olhao volumio[1082]: info: Upmpdcli Daemon Started Apr 20 09:18:38 loja-olhao volumio[1082]: info: Shairport-Sync Started Apr 20 09:18:38 loja-olhao volumio[1082]: Error adding Membership: Error: addMembership EINVAL Apr 20 09:18:38 loja-olhao volumio[1082]: info: Shairport-Sync Started Apr 20 09:18:38 loja-olhao volumio[1082]: Discovery: this is already registered, ce3dfd72-6001-4b38-9a86-4dd8136adeee Apr 20 09:18:38 loja-olhao volumio[1082]: info: mDNS: Found device loja-olhao Apr 20 09:18:38 loja-olhao volumio[1082]: info: ControllerAutoPlay - mpd ready Apr 20 09:18:38 loja-olhao sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetState Apr 20 09:18:38 loja-olhao sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:38 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:38 loja-olhao sudo[1268]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:38 loja-olhao sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 09:18:38 loja-olhao volumio[1082]: info: Listing playlists Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetQueue Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreStateMachine::getQueue Apr 20 09:18:38 loja-olhao sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:38 loja-olhao volumio[1082]: info: CorePlayQueue::getQueue Apr 20 09:18:38 loja-olhao sudo[1277]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:38 loja-olhao sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:38 loja-olhao sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:38 loja-olhao sudo[1280]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:38 loja-olhao volumio[1082]: error: Cannot refresh My Volumio Token: [object Object] Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetState Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:38 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:38 loja-olhao sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetState Apr 20 09:18:38 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:38 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:38 loja-olhao sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:38 loja-olhao sudo[1295]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:38 loja-olhao sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:38 loja-olhao sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:38 loja-olhao sudo[1302]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:38 loja-olhao volumio[1082]: info: [ASDebug] CurState: stop PrevState: na Apr 20 09:18:38 loja-olhao volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 20 09:18:39 loja-olhao volumio[1082]: info: [ASDebug] Pulsing GPIO for 500ms Apr 20 09:18:39 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: ON Apr 20 09:18:39 loja-olhao volumio[1082]: info: Fetching Streaming Services browse cache Apr 20 09:18:39 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Apr 20 09:18:39 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:39 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Apr 20 09:18:39 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 09:18:39 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: OFF Apr 20 09:18:41 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:41] [connect] Successful connection Apr 20 09:18:41 loja-olhao volumio-remote-updater[635]: [2025-04-20 09:18:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1745140721 101 Apr 20 09:18:41 loja-olhao volumio[1082]: info: Connecting to daemon Apr 20 09:18:41 loja-olhao volumio[1082]: info: SPOP command error: Apr 20 09:18:41 loja-olhao volumio[1082]: info: message=connect ECONNREFUSED 127.0.0.1:6602, stack=Error: connect ECONNREFUSED 127.0.0.1:6602 Apr 20 09:18:41 loja-olhao volumio[1082]: at Object._errnoException (util.js:1022:11) Apr 20 09:18:41 loja-olhao volumio[1082]: at _exceptionWithHostPort (util.js:1044:20) Apr 20 09:18:41 loja-olhao volumio[1082]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14), code=ECONNREFUSED, errno=ECONNREFUSED, syscall=connect, address=127.0.0.1, port=6602 Apr 20 09:18:41 loja-olhao volumio[1082]: info: SPOP status error: Apr 20 09:18:41 loja-olhao volumio[1082]: info: message=connect ECONNREFUSED 127.0.0.1:6602, stack=Error: connect ECONNREFUSED 127.0.0.1:6602 Apr 20 09:18:41 loja-olhao volumio[1082]: at Object._errnoException (util.js:1022:11) Apr 20 09:18:41 loja-olhao volumio[1082]: at _exceptionWithHostPort (util.js:1044:20) Apr 20 09:18:41 loja-olhao volumio[1082]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14), code=ECONNREFUSED, errno=ECONNREFUSED, syscall=connect, address=127.0.0.1, port=6602 Apr 20 09:18:41 loja-olhao volumio[1082]: info: Spotify access token expires at 1745144321808 Apr 20 09:18:41 loja-olhao volumio[1082]: info: Spotify access token is BQAW5YPiGvilLRZakA-wzinxnUEmIrIIID_7czxUMxcrCK7O8JjdE6oNN8JWCw6Xs2hwQCTSYrPf-7kqR_KQtNEzaSfZr0Aw3PheKoShSCesMNWXsoNKneUuFowcjdK-a-GIPxlTgPY Apr 20 09:18:41 loja-olhao volumio[1082]: info: Spotify credentials grant success Apr 20 09:18:43 loja-olhao volumio[1082]: info: ControllerAutoPlay - getting queue Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetQueue Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreStateMachine::getQueue Apr 20 09:18:43 loja-olhao volumio[1082]: info: CorePlayQueue::getQueue Apr 20 09:18:43 loja-olhao volumio[1082]: info: ControllerAutoPlay - start playing -> queue is not empty Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPlay Apr 20 09:18:43 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreStateMachine::play index undefined Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:18:43 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:43 loja-olhao volumio[1082]: info: CoreStateMachine::startPlaybackTimer Apr 20 09:18:43 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:18:43 loja-olhao volumio[1082]: info: [1745140723074] ControllerWebradio::clearAddPlayTrack Apr 20 09:18:43 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:18:43 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:43 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:43 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand clear Apr 20 09:18:43 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:43 loja-olhao volumio[1082]: info: Apr 20 09:18:43 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:18:43 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:18:43 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:43 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand load "http://mcrscast1.mcr.iol.pt/comercial.mp3" Apr 20 09:18:43 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:43 loja-olhao volumio[1082]: info: Apr 20 09:18:43 loja-olhao volumio[1082]: info: ------------------------------ 7ms Apr 20 09:18:44 loja-olhao volumio[1082]: info: AutoStart - getting queue Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioGetQueue Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::getQueue Apr 20 09:18:44 loja-olhao volumio[1082]: info: CorePlayQueue::getQueue Apr 20 09:18:44 loja-olhao volumio[1082]: info: AutoStart - start playing -> queue is not empty Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPlay Apr 20 09:18:44 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::play index 4 Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::stop Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:18:44 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::play index undefined Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:18:44 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:18:44 loja-olhao volumio[1082]: info: CoreStateMachine::startPlaybackTimer Apr 20 09:18:44 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:18:44 loja-olhao volumio[1082]: info: [1745140724337] ControllerWebradio::clearAddPlayTrack Apr 20 09:18:44 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:18:44 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:48 loja-olhao nmbd[863]: [2025/04/20 09:18:48.463975, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 20 09:18:48 loja-olhao nmbd[863]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 20 09:18:49 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand add "http://mcrscast1.mcr.iol.pt/comercial.mp3" Apr 20 09:18:49 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:49 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:49 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand clear Apr 20 09:18:49 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:49 loja-olhao volumio[1082]: info: Apr 20 09:18:49 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:18:49 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:18:49 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:49 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:49 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 09:18:49 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand play Apr 20 09:18:49 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand load "https://radiocast.rtp.pt/antena380a.mp3" Apr 20 09:18:49 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:49 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:49 loja-olhao volumio[1082]: info: Apr 20 09:18:49 loja-olhao volumio[1082]: info: ------------------------------ 11ms Apr 20 09:18:49 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:52 loja-olhao volumio[1082]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.599&uuid=cb901f9c3d9cb7e900b039d462455512" http://updates.volumio.org:7070/downloader-v1/track-device Apr 20 09:18:52 loja-olhao volumio[1082]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 20 09:18:52 loja-olhao volumio[1082]: Dload Upload Total Spent Left Speed Apr 20 09:18:52 loja-olhao volumio[1082]: [551B blob data] Apr 20 09:18:52 loja-olhao volumio[1082]: retrying in 5 seconds, trial 0 Apr 20 09:18:52 loja-olhao volumio[1082]: Volumio Calling Home Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand add "https://radiocast.rtp.pt/antena380a.mp3" Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: Apr 20 09:18:55 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:18:55 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand play Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: ------------------------------ 7ms Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: Apr 20 09:18:55 loja-olhao volumio[1082]: ---------------------------- MPD announces state update: player Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::getState Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand status Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: Apr 20 09:18:55 loja-olhao volumio[1082]: ---------------------------- MPD announces state update: player Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::getState Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand status Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::parseState Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: Apr 20 09:18:55 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:18:55 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::parseState Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::parseTrackInfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: sending command... Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::pushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::servicePushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:18:55 loja-olhao volumio[1082]: 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":"antena380a.mp3","artist":"Antena 3 - Main","album":null,"uri":"https://radiocast.rtp.pt/antena380a.mp3","trackType":"mp3"} Apr 20 09:18:55 loja-olhao volumio[1082]: info: CURRENT POSITION 4 Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState stateService play Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: ------------------------------ 64ms Apr 20 09:18:55 loja-olhao volumio[1082]: info: ------------------------------ 50ms Apr 20 09:18:55 loja-olhao sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:55 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::parseTrackInfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: ControllerMpd::pushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::servicePushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:18:55 loja-olhao volumio[1082]: 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":"antena380a.mp3","artist":"Antena 3 - Main","album":null,"uri":"https://radiocast.rtp.pt/antena380a.mp3","trackType":"mp3"} Apr 20 09:18:55 loja-olhao volumio[1082]: info: CURRENT POSITION 4 Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState stateService play Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::syncState currentStatus play Apr 20 09:18:55 loja-olhao volumio[1082]: info: Received an update from plugin. extracting info from payload Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:55 loja-olhao sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:55 loja-olhao sudo[1378]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:55 loja-olhao sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:55 loja-olhao sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:55 loja-olhao sudo[1384]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:18:55 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:18:55 loja-olhao volumio[1082]: info: ------------------------------ 168ms Apr 20 09:18:55 loja-olhao volumio[1082]: info: [ASDebug] CurState: PrevState: stop Apr 20 09:18:55 loja-olhao sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:18:55 loja-olhao volumio[1082]: info: [ASDebug] CurState: play PrevState: stop Apr 20 09:18:55 loja-olhao volumio[1082]: info: [ASDebug] Pulsing GPIO for 500ms Apr 20 09:18:55 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: ON Apr 20 09:18:56 loja-olhao volumio[1082]: info: [ASDebug] CurState: play PrevState: play Apr 20 09:18:56 loja-olhao sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:18:56 loja-olhao sudo[1396]: pam_unix(sudo:session): session closed for user root Apr 20 09:18:56 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: OFF Apr 20 09:18:59 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 09:18:59 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 09:19:07 loja-olhao volumio[1082]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.599&uuid=cb901f9c3d9cb7e900b039d462455512" http://updates.volumio.org:7070/downloader-v1/track-device Apr 20 09:19:07 loja-olhao volumio[1082]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 20 09:19:07 loja-olhao volumio[1082]: Dload Upload Total Spent Left Speed Apr 20 09:19:07 loja-olhao volumio[1082]: [630B blob data] Apr 20 09:19:07 loja-olhao volumio[1082]: retrying in 5 seconds, trial 1 Apr 20 09:19:07 loja-olhao volumio[1082]: Volumio Calling Home Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPlay Apr 20 09:19:10 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::play index 31 Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::stop Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:10 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::stPlaybackTimer Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::updateTrackBlock Apr 20 09:19:10 loja-olhao volumio[1082]: info: CorePlayQueue::getTrackBlock Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:19:10 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::serviceStop Apr 20 09:19:10 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 4 Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreCommandRouter::serviceStop Apr 20 09:19:10 loja-olhao volumio[1082]: info: [1745140750056] ControllerWebradio::stop Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:19:10 loja-olhao sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: [ASDebug] CurState: stop PrevState: play Apr 20 09:19:10 loja-olhao volumio[1082]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 20 09:19:10 loja-olhao sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:19:10 loja-olhao sudo[1510]: pam_unix(sudo:session): session closed for user root Apr 20 09:19:10 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::play index undefined Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:10 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 31 Apr 20 09:19:10 loja-olhao volumio[1082]: info: CoreStateMachine::startPlaybackTimer Apr 20 09:19:10 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 31 Apr 20 09:19:10 loja-olhao volumio[1082]: info: [1745140750092] ControllerWebradio::clearAddPlayTrack Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: Apr 20 09:19:10 loja-olhao volumio[1082]: ---------------------------- MPD announces state update: player Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::getState Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand status Apr 20 09:19:10 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand clear Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: Apr 20 09:19:10 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:19:10 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:19:10 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:10 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::parseState Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand load "http://provisioning.streamtheworld.com/pls/MEGA_HITS.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTY5MTIzODM0MiwiaXNzIjoidGlzcnYifQ.29QVNitoNPCMEKhQo04FabFHsvEFjt5JhBXMpyy3f_o" Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:10 loja-olhao volumio[1082]: info: Apr 20 09:19:10 loja-olhao volumio[1082]: info: ------------------------------ 9ms Apr 20 09:19:10 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::parseTrackInfo Apr 20 09:19:10 loja-olhao volumio[1082]: info: ControllerMpd::pushError Apr 20 09:19:10 loja-olhao volumio[1082]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Apr 20 09:19:10 loja-olhao volumio[1082]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:291:54) Apr 20 09:19:10 loja-olhao volumio[1082]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 20 09:19:10 loja-olhao volumio[1082]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 20 09:19:10 loja-olhao volumio[1082]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 20 09:19:10 loja-olhao volumio[1082]: info: ------------------------------ 23ms Apr 20 09:19:10 loja-olhao volumio[1082]: info: [ASDebug] Pulsing GPIO for 500ms Apr 20 09:19:10 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: ON Apr 20 09:19:11 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: OFF Apr 20 09:19:15 loja-olhao wpa_supplicant[944]: wlan0: CTRL-EVENT-DISCONNECTED bssid=98:35:ed:c9:f8:d8 reason=3 locally_generated=1 Apr 20 09:19:15 loja-olhao dhcpcd[955]: wlan0: carrier lost Apr 20 09:19:15 loja-olhao dhcpcd[955]: eth0: adding route to 10.161.2.0/24 Apr 20 09:19:15 loja-olhao dhcpcd[955]: eth0: adding default route via 10.161.2.254 Apr 20 09:19:15 loja-olhao dhcpcd[955]: wlan0: deleting host route to 192.168.1.198 via 127.0.0.1 Apr 20 09:19:15 loja-olhao dhcpcd[955]: wlan0: deleting route to 192.168.1.0/24 Apr 20 09:19:15 loja-olhao dhcpcd[955]: wlan0: deleting default route via 192.168.1.1 Apr 20 09:19:15 loja-olhao avahi-daemon[656]: Withdrawing address record for 192.168.1.198 on wlan0. Apr 20 09:19:15 loja-olhao avahi-daemon[656]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.198. Apr 20 09:19:15 loja-olhao wpa_supplicant[944]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Apr 20 09:19:15 loja-olhao avahi-daemon[656]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 20 09:19:15 loja-olhao volumio[1082]: info: mDNS: A device disapperared from network Apr 20 09:19:15 loja-olhao volumio[1082]: info: mDNS: Device loja-olhao disapperared from network Apr 20 09:19:15 loja-olhao dhcpcd[955]: eth0: removing route to 10.161.2.0/24 Apr 20 09:19:15 loja-olhao dhcpcd[955]: eth0: removing default route via 10.161.2.254 Apr 20 09:19:15 loja-olhao wpa_supplicant[944]: wlan0: Trying to associate with SSID 'Vodafone-C9F8CD' Apr 20 09:19:17 loja-olhao ntpd[734]: Deleting interface #4 wlan0, 192.168.1.198#123, interface stats: received=0, sent=0, dropped=0, active_time=61 secs Apr 20 09:19:17 loja-olhao ntpd[734]: peers refreshed Apr 20 09:19:18 loja-olhao wpa_supplicant[944]: wlan0: Associated with 98:35:ed:c9:f8:d8 Apr 20 09:19:18 loja-olhao wpa_supplicant[944]: wlan0: WPA: Key negotiation completed with 98:35:ed:c9:f8:d8 [PTK=CCMP GTK=TKIP] Apr 20 09:19:18 loja-olhao wpa_supplicant[944]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:35:ed:c9:f8:d8 completed [id=0 id_str=] Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: carrier acquired Apr 20 09:19:18 loja-olhao wpa_supplicant[944]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=PT Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: rebinding lease of 192.168.1.198 Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: leased 192.168.1.198 for 3600 seconds Apr 20 09:19:18 loja-olhao avahi-daemon[656]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.198. Apr 20 09:19:18 loja-olhao avahi-daemon[656]: New relevant interface wlan0.IPv4 for mDNS. Apr 20 09:19:18 loja-olhao avahi-daemon[656]: Registering new address record for 192.168.1.198 on wlan0.IPv4. Apr 20 09:19:18 loja-olhao dhcpcd[955]: eth0: adding route to 10.161.2.0/24 Apr 20 09:19:18 loja-olhao dhcpcd[955]: eth0: adding default route via 10.161.2.254 Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: adding host route to 192.168.1.198 via 127.0.0.1 Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: adding route to 192.168.1.0/24 Apr 20 09:19:18 loja-olhao dhcpcd[955]: wlan0: adding default route via 192.168.1.1 Apr 20 09:19:18 loja-olhao dhcpcd[955]: eth0: removing route to 10.161.2.0/24 Apr 20 09:19:18 loja-olhao dhcpcd[955]: eth0: removing default route via 10.161.2.254 Apr 20 09:19:19 loja-olhao volumio[1082]: Discovery: adding ce3dfd72-6001-4b38-9a86-4dd8136adeee Apr 20 09:19:19 loja-olhao volumio[1082]: info: mDNS: Found device loja-olhao Apr 20 09:19:19 loja-olhao sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:19:19 loja-olhao sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:19:19 loja-olhao sudo[1584]: pam_unix(sudo:session): session closed for user root Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPlay Apr 20 09:19:19 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::play index 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::stop Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::play index undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::startPlaybackTimer Apr 20 09:19:19 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: [1745140759778] ControllerWebradio::clearAddPlayTrack Apr 20 09:19:19 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:19:19 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPlay Apr 20 09:19:19 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::play index 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::stop Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: UNSET VOLATILE Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::play index undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 20 09:19:19 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: CoreStateMachine::startPlaybackTimer Apr 20 09:19:19 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:19 loja-olhao volumio[1082]: info: [1745140759791] ControllerWebradio::clearAddPlayTrack Apr 20 09:19:19 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand stop Apr 20 09:19:19 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:20 loja-olhao ntpd[734]: Listen normally on 5 wlan0 192.168.1.198 UDP 123 Apr 20 09:19:20 loja-olhao ntpd[734]: peers refreshed Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand play Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:19:22 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand clear Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand clear Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:19:22 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: ---------------------------- MPD announces state update: player Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: ---------------------------- MPD announces system playlist update Apr 20 09:19:22 loja-olhao volumio[1082]: info: Ignoring MPD Status Update Apr 20 09:19:22 loja-olhao volumio[1082]: info: ------------------------------ 35ms Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::getState Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand status Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand load "http://mcrscast1.mcr.iol.pt/comercial.mp3" Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::sendMpdCommand load "http://mcrscast1.mcr.iol.pt/comercial.mp3" Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: sending command... Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: info: Apr 20 09:19:22 loja-olhao volumio[1082]: info: ------------------------------ 36ms Apr 20 09:19:22 loja-olhao volumio[1082]: info: parsing response... Apr 20 09:19:22 loja-olhao volumio[1082]: info: ------------------------------ 10ms Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::parseState Apr 20 09:19:22 loja-olhao volumio[1082]: info: ControllerMpd::pushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::servicePushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::syncState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:19:22 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:22 loja-olhao volumio[1082]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 20 09:19:22 loja-olhao volumio[1082]: info: CURRENT POSITION 0 Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::syncState stateService stop Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:19:22 loja-olhao sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:19:22 loja-olhao sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:19:22 loja-olhao volumio[1082]: info: No code Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::pushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreStateMachine::getState Apr 20 09:19:22 loja-olhao sudo[1601]: pam_unix(sudo:session): session closed for user root Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::volumioPushState Apr 20 09:19:22 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 09:19:22 loja-olhao sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:19:22 loja-olhao sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:19:22 loja-olhao volumio[1082]: info: ------------------------------ 98ms Apr 20 09:19:22 loja-olhao sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 09:19:22 loja-olhao sudo[1606]: pam_unix(sudo:session): session closed for user root Apr 20 09:19:22 loja-olhao volumio[1082]: info: [ASDebug] CurState: play PrevState: stop Apr 20 09:19:22 loja-olhao volumio[1082]: info: [ASDebug] Pulsing GPIO for 500ms Apr 20 09:19:22 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: ON Apr 20 09:19:22 loja-olhao volumio[1082]: info: [ASDebug] CurState: play PrevState: play Apr 20 09:19:22 loja-olhao volumio[1082]: info: [ASDebug] CurState: play PrevState: play Apr 20 09:19:22 loja-olhao sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 09:19:22 loja-olhao sudo[1614]: pam_unix(sudo:session): session closed for user root Apr 20 09:19:23 loja-olhao volumio[1082]: info: [ASDebug] Togle GPIO: OFF Apr 20 09:19:23 loja-olhao volumio[1082]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.599&uuid=cb901f9c3d9cb7e900b039d462455512" http://updates.volumio.org:7070/downloader-v1/track-device Apr 20 09:19:23 loja-olhao volumio[1082]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 20 09:19:23 loja-olhao volumio[1082]: Dload Upload Total Spent Left Speed Apr 20 09:19:23 loja-olhao volumio[1082]: [551B blob data] Apr 20 09:19:23 loja-olhao volumio[1082]: retrying in 5 seconds, trial 2 Apr 20 09:19:23 loja-olhao volumio[1082]: Volumio Calling Home Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: No input data Apr 20 09:19:26 loja-olhao volumio[1082]: info: CorePlayQueue::getTrack 0 Apr 20 09:19:26 loja-olhao volumio[1082]: info: CoreCommandRouter::executeOnPlugin: webradio , goto Apr 20 09:19:26 loja-olhao volumio[1082]: info: Error : CoreCommandRouter::executeOnPlugin: No method [goto] in plugin webradio Apr 20 09:19:26 loja-olhao volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:19:26 loja-olhao volumio[1082]: TypeError: Cannot read property 'then' of undefined Apr 20 09:19:26 loja-olhao volumio[1082]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:434:18) Apr 20 09:19:26 loja-olhao volumio[1082]: at emitTwo (events.js:126:13) Apr 20 09:19:26 loja-olhao volumio[1082]: at Socket.emit (events.js:214:7) Apr 20 09:19:26 loja-olhao volumio[1082]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 20 09:19:26 loja-olhao volumio[1082]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Apr 20 09:19:26 loja-olhao volumio[1082]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 20 09:19:26 loja-olhao volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 09:19:27 loja-olhao sudo[1638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 09:18 Apr 20 09:19:27 loja-olhao sudo[1638]: 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"