-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-01-14 19:07:24 CET. -- Jan 14 19:06:04 motivo volumio[937]: info: CoreCommandRouter::volumioGetState Jan 14 19:06:04 motivo volumio[937]: info: CorePlayQueue::getTrack 0 Jan 14 19:06:07 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 19:06:07 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 19:06:38 motivo volumio[937]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/motivo/buster/armhf Jan 14 19:06:40 motivo volumio[937]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/motivo/buster/armhf Jan 14 19:06:40 motivo volumio[937]: info: Folder /tmp/plugins removed Jan 14 19:06:40 motivo volumio[937]: info: Check plugin dependencies Jan 14 19:06:41 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 14 19:06:41 motivo volumio[937]: info: Checking if plugin already exists Jan 14 19:06:41 motivo volumio[937]: info: Rename folder Jan 14 19:06:41 motivo volumio[937]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 19:06:41 motivo volumio[937]: info: Move to category Jan 14 19:06:41 motivo volumio[937]: info: Checking if install.sh is present Jan 14 19:06:41 motivo volumio[937]: info: Executing install.sh Jan 14 19:06:41 motivo sudo[5960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Jan 14 19:06:41 motivo sudo[5960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 19:06:41 motivo volumio[937]: info: Installing Go-librespot Jan 14 19:06:41 motivo volumio[937]: info: Checking old vollibrespot installs Jan 14 19:06:41 motivo systemd[1]: Reloading. Jan 14 19:06:42 motivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 14 19:06:42 motivo systemd[1]: volumioStreaming.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 14 19:06:42 motivo volumio[937]: info: Dowloading daemon Jan 14 19:06:45 motivo volumio[937]: info: Creating Start Script Jan 14 19:06:45 motivo systemd[1]: Reloading. Jan 14 19:06:45 motivo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 14 19:06:45 motivo sudo[5960]: pam_unix(sudo:session): session closed for user root Jan 14 19:06:45 motivo volumio[937]: Plugin install end detected on script Jan 14 19:06:45 motivo volumio[937]: info: Install script completed Jan 14 19:06:45 motivo volumio[937]: info: Adding reference to registry Jan 14 19:06:45 motivo volumio[937]: info: Done installing plugin. Jan 14 19:06:45 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 19:06:45 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 19:06:45 motivo volumio[937]: info: Folder /tmp/plugins removed Jan 14 19:06:45 motivo volumio[937]: info: Folder /tmp/downloaded_plugin.zip removed Jan 14 19:06:45 motivo volumio[937]: info: Folder /data/temp removed Jan 14 19:06:47 motivo volumio[937]: info: Enabling plugin spop Jan 14 19:06:47 motivo volumio[937]: info: Loading plugin "spop"... Jan 14 19:06:48 motivo volumio[937]: info: PLUGIN START: spop Jan 14 19:06:48 motivo volumio[937]: info: Creating Spotify config file Jan 14 19:06:48 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 14 19:06:48 motivo volumio[937]: info: Done. Jan 14 19:06:48 motivo volumio[937]: info: Spotify config file written Jan 14 19:06:48 motivo volumio[937]: info: No need to fix Spotify hosts Jan 14 19:06:48 motivo sudo[6066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 14 19:06:48 motivo sudo[6066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 19:06:48 motivo systemd[1]: Started go-librespot Daemon. Jan 14 19:06:48 motivo go-librespot[6068]: Librespot-go daemon starting... Jan 14 19:06:48 motivo sudo[6066]: pam_unix(sudo:session): session closed for user root Jan 14 19:06:48 motivo go-librespot[6068]: time="2025-01-14T19:06:48+01:00" level=info msg="generated new device id: 3bfdfc53c1939287702c2fd7cefdd0b3fc7c4a9a" Jan 14 19:06:48 motivo go-librespot[6068]: time="2025-01-14T19:06:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 14 19:06:48 motivo go-librespot[6068]: time="2025-01-14T19:06:48+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 14 19:06:48 motivo go-librespot[6068]: time="2025-01-14T19:06:48+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 14 19:06:48 motivo go-librespot[6068]: time="2025-01-14T19:06:48+01:00" level=debug msg="zeroconf server listening on port 42361" Jan 14 19:06:51 motivo volumio[937]: info: go-librespot daemon successfully initialized Jan 14 19:06:54 motivo volumio[937]: info: Initializing connection to go-librespot Websocket Jan 14 19:06:54 motivo go-librespot[6068]: time="2025-01-14T19:06:54+01:00" level=debug msg="new websocket client" Jan 14 19:06:54 motivo volumio[937]: info: Connection to go-librespot Websocket established Jan 14 19:06:57 motivo volumio[937]: info: Getting Spotify volume Jan 14 19:06:57 motivo volumio[937]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 14 19:06:57 motivo volumio[937]: info: CoreCommandRouter::volumioGetState Jan 14 19:06:57 motivo volumio[937]: info: CorePlayQueue::getTrack 0 Jan 14 19:06:57 motivo volumio[937]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 14 19:06:57 motivo volumio[937]: SPOTIFY: SPOTIFY VOLUME undefined Jan 14 19:06:57 motivo volumio[937]: SPOTIFY: VOLUMIO VOLUME 100 Jan 14 19:06:57 motivo volumio[937]: info: Aligning Spotify Volume to Volumio Volume Jan 14 19:06:57 motivo volumio[937]: info: CoreCommandRouter::volumioGetState Jan 14 19:06:57 motivo volumio[937]: info: CorePlayQueue::getTrack 0 Jan 14 19:06:57 motivo volumio[937]: info: Setting Spotify Volume from Volumio: 100 Jan 14 19:06:59 motivo volumio[937]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jan 14 19:06:59 motivo volumio[937]: info: Sending Spotify command with payload to local API: /player/volume Jan 14 19:07:06 motivo volumio[937]: info: CoreCommandRouter::volumioGetState Jan 14 19:07:06 motivo volumio[937]: info: CorePlayQueue::getTrack 0 Jan 14 19:07:07 motivo volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 19:07:07 motivo volumio[937]: info: Retrieving Cloud Streaming UI Jan 14 19:07:07 motivo volumio[937]: info: Getting Tidal Cloud Configuration Jan 14 19:07:07 motivo volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 19:07:07 motivo volumio[937]: info: Getting Qobuz Cloud Configuration Jan 14 19:07:07 motivo volumio[937]: info: Asking plugin for UI Config Jan 14 19:07:07 motivo volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 19:07:07 motivo volumio[937]: info: Getting Spotify Cloud Configuration Jan 14 19:07:07 motivo volumio[937]: info: Asking plugin for UI Config Jan 14 19:07:07 motivo volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 19:07:07 motivo volumio[937]: info: Saving Spotify Acccount Jan 14 19:07:07 motivo volumio[937]: info: Got it Jan 14 19:07:07 motivo volumio[937]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 14 19:07:07 motivo volumio[937]: info: Got it Jan 14 19:07:07 motivo volumio[937]: info: Got Tidal Cloud Configuration Jan 14 19:07:07 motivo volumio[937]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::volumioGetBrowseSources Jan 14 19:07:08 motivo ntpd[825]: 217.14.146.53 local addr 192.168.0.232 -> Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 14 19:07:08 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 14 19:07:12 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 14 19:07:16 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 14 19:07:20 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 14 19:07:24 motivo volumio[937]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Jan 14 19:07:24 motivo volumio[937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 19:07:24 motivo volumio[937]: TypeError: Cannot read property 'then' of undefined Jan 14 19:07:24 motivo volumio[937]: at outputs.disableAudioOutput (/volumio/app/plugins/audio_interface/outputs/index.js:273:9) Jan 14 19:07:24 motivo volumio[937]: at CoreCommandRouter.disableAudioOutput (/volumio/app/index.js:2252:30) Jan 14 19:07:24 motivo volumio[937]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1438:26) Jan 14 19:07:24 motivo volumio[937]: at Socket.emit (events.js:315:20) Jan 14 19:07:24 motivo volumio[937]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 14 19:07:24 motivo volumio[937]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 14 19:07:24 motivo volumio[937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 19:07:24 motivo sudo[6139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 19:06 Jan 14 19:07:24 motivo sudo[6139]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:02:37 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="f3429790fcb2ce685253f61650bd5f66"