-- Logs begin at Thu 2022-01-20 03:15:29 UTC, end at Fri 2025-02-28 23:35:56 UTC. -- Feb 28 23:35:15 volumio systemd[1]: Time has been changed Feb 28 23:35:16 volumio wireless.js[869]: trying... Feb 28 23:35:16 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:16 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:16 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:17 volumio wireless.js[869]: trying... Feb 28 23:35:17 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:17 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:17 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:18 volumio wireless.js[869]: trying... Feb 28 23:35:18 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:18 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:18 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:19 volumio wireless.js[869]: trying... Feb 28 23:35:19 volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:19 volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:19 volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:20 volumio wireless.js[869]: trying... Feb 28 23:35:20 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:20 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:20 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:21 volumio volumio-remote-updater[718]: [2025-02-28 23:35:21] [info] asio async_connect error: system:111 (Connection refused) Feb 28 23:35:21 volumio volumio-remote-updater[718]: [2025-02-28 23:35:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 23:35:21 volumio volumio-remote-updater[718]: [2025-02-28 23:35:21] [error] handle_connect error: Underlying Transport Error Feb 28 23:35:21 volumio wireless.js[869]: trying... Feb 28 23:35:21 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:21 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:21 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:22 volumio wireless.js[869]: trying... Feb 28 23:35:22 volumio sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:22 volumio sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:22 volumio sudo[1157]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:23 volumio wireless.js[869]: trying... Feb 28 23:35:23 volumio sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:23 volumio sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:23 volumio sudo[1166]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:24 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:24 volumio wireless.js[869]: trying... Feb 28 23:35:24 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:24 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:24 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:25 volumio wireless.js[869]: trying... Feb 28 23:35:25 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:25 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:25 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:26 volumio wireless.js[869]: trying... Feb 28 23:35:26 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:26 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:26 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:27 volumio wireless.js[869]: trying... Feb 28 23:35:27 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:27 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:27 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:28 volumio wireless.js[869]: trying... Feb 28 23:35:28 volumio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:28 volumio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:28 volumio sudo[1211]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:29 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:29 volumio wireless.js[869]: trying... Feb 28 23:35:30 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:30 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:30 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:31 volumio wireless.js[869]: trying... Feb 28 23:35:31 volumio sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:31 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:31 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:31 volumio volumio-remote-updater[718]: [2025-02-28 23:35:31] [info] asio async_connect error: system:111 (Connection refused) Feb 28 23:35:31 volumio volumio-remote-updater[718]: [2025-02-28 23:35:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 23:35:31 volumio volumio-remote-updater[718]: [2025-02-28 23:35:31] [error] handle_connect error: Underlying Transport Error Feb 28 23:35:32 volumio wireless.js[869]: trying... Feb 28 23:35:32 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:32 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:32 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:33 volumio wireless.js[869]: trying... Feb 28 23:35:33 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 28 23:35:33 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:33 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:34 volumio wireless.js[869]: Overtime, starting plan B Feb 28 23:35:34 volumio systemd[1]: Started Wireless Services. Feb 28 23:35:34 volumio systemd[1]: Starting Volumio Backend Module... Feb 28 23:35:34 volumio systemd[1]: Started Volumio Backend Module. Feb 28 23:35:34 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 28 23:35:34 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 28 23:35:34 volumio systemd[1]: Starting Multi-User System. Feb 28 23:35:34 volumio systemd[1]: Reached target Multi-User System. Feb 28 23:35:34 volumio systemd[1]: Starting Graphical Interface. Feb 28 23:35:34 volumio systemd[1]: Reached target Graphical Interface. Feb 28 23:35:34 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 28 23:35:34 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 28 23:35:34 volumio systemd[1]: Startup finished in 9.421s (kernel) + 35.120s (userspace) = 44.541s. Feb 28 23:35:34 volumio volumio-streaming-daemon[1257]: ############################ Feb 28 23:35:34 volumio volumio-streaming-daemon[1257]: # Volumio Streaming Daemon # Feb 28 23:35:34 volumio volumio-streaming-daemon[1257]: # Running on port 7777 # Feb 28 23:35:34 volumio volumio-streaming-daemon[1257]: ############################ Feb 28 23:35:34 volumio volumio-streaming-daemon[1257]: Environment: production Feb 28 23:35:35 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:36 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:36 volumio volumio[1256]: info: ----- Volumio2 ---- Feb 28 23:35:36 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:36 volumio volumio[1256]: info: ----- System startup ---- Feb 28 23:35:36 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:37 volumio volumio[1256]: info: MYVOLUMIO Environment detected Feb 28 23:35:38 volumio volumio[1256]: info: Plugin folders cleanup Feb 28 23:35:38 volumio volumio[1256]: info: Scanning into folder /volumio/app/plugins/ Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category audio_interface Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category miscellanea Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category music_service Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category plugins.json Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category system_controller Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category user_interface Feb 28 23:35:38 volumio volumio[1256]: info: Scanning into folder /data/plugins/ Feb 28 23:35:38 volumio volumio[1256]: info: Scanning category music_service Feb 28 23:35:38 volumio volumio[1256]: info: Plugin folders cleanup completed Feb 28 23:35:38 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:38 volumio volumio[1256]: info: ----- Core plugins startup ---- Feb 28 23:35:38 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:38 volumio volumio[1256]: info: Loading plugins from folder /volumio/app/plugins/ Feb 28 23:35:38 volumio volumio[1256]: info: Adding plugin upnp to MyMusic Plugins Feb 28 23:35:38 volumio volumio[1256]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 28 23:35:38 volumio volumio[1256]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 28 23:35:38 volumio volumio[1256]: info: Loading plugins from folder /data/plugins/ Feb 28 23:35:38 volumio volumio[1256]: info: Loading plugin "system"... Feb 28 23:35:38 volumio volumio[1256]: info: Loading plugin "appearance"... Feb 28 23:35:40 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:40 volumio volumio[1256]: info: Loading plugin "network"... Feb 28 23:35:40 volumio volumio[1256]: info: Refreshing Cached IP Addresses Feb 28 23:35:40 volumio sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 23:35:40 volumio volumio[1256]: info: Loading plugin "services"... Feb 28 23:35:40 volumio volumio[1256]: info: Loading plugin "alsa_controller"... Feb 28 23:35:40 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:40 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:40 volumio sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 23:35:40 volumio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:40 volumio sudo[1285]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "wizard"... Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "volumio_command_line_client"... Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "upnp"... Feb 28 23:35:41 volumio volumio[1256]: info: [1740785741154] Starting Upmpd Daemon Feb 28 23:35:41 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "my_music"... Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "mpd"... Feb 28 23:35:41 volumio volumio-remote-updater[718]: [2025-02-28 23:35:41] [connect] Successful connection Feb 28 23:35:41 volumio volumio[1256]: info: Loading plugin "upnp_browser"... Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "networkfs"... Feb 28 23:35:42 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "alarm-clock"... Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "airplay_emulation"... Feb 28 23:35:42 volumio volumio[1256]: info: Starting Shairport Sync Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "last_100"... Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "webradio"... Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "i2s_dacs"... Feb 28 23:35:42 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 23:35:42 volumio volumio[1256]: info: Loading plugin "volumiodiscovery"... Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 23:35:43 volumio node[1256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 23:35:43 volumio node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 23:35:43 volumio node[1256]: *** WARNING *** For more information see Feb 28 23:35:43 volumio node[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 23:35:43 volumio node[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 23:35:43 volumio node[1256]: *** WARNING *** For more information see Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** For more information see Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 23:35:43 volumio volumio[1256]: *** WARNING *** For more information see Feb 28 23:35:43 volumio volumio[1256]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 28 23:35:43 volumio volumio[1256]: Discovery: StartAdv! undefined Feb 28 23:35:43 volumio volumio[1256]: Discovery: Started advertising... Volumio - undefined Feb 28 23:35:43 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "outputs"... Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "albumart"... Feb 28 23:35:43 volumio volumio[1256]: info: Plugin example_plugin is not enabled Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "inputs"... Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "updater_comm"... Feb 28 23:35:43 volumio volumio[1256]: info: Plugin mpdemulation is not enabled Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "rest_api"... Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "websocket"... Feb 28 23:35:43 volumio volumio[1256]: info: Loading plugin "spop"... Feb 28 23:35:43 volumio volumio[1256]: Forking 3 albumart workers Feb 28 23:35:44 volumio volumio[1256]: Starting albumart workers Feb 28 23:35:44 volumio volumio[1256]: Starting albumart workers Feb 28 23:35:44 volumio volumio[1256]: Starting albumart workers Feb 28 23:35:44 volumio volumio[1256]: info: ___________ START PLUGINS ___________ Feb 28 23:35:44 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 23:35:44 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 23:35:44 volumio volumio[1256]: info: [1740785744994] CoreMusicLibrary::Adding element Media Servers Feb 28 23:35:44 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 23:35:45 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 23:35:45 volumio volumio[1256]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 23:35:45 volumio volumio[1256]: info: [1740785745155] CoreMusicLibrary::Adding element Last_100 Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 23:35:45 volumio volumio[1256]: info: [1740785745159] CoreMusicLibrary::Adding element Webradio Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 23:35:45 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:45 volumio volumio[1256]: info: ----- MyVolumio plugins startup ---- Feb 28 23:35:45 volumio volumio[1256]: info: ------------------------------------------- Feb 28 23:35:45 volumio volumio[1256]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 28 23:35:45 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Feb 28 23:35:45 volumio volumio[1256]: info: Loading i18n strings for locale en Feb 28 23:35:45 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:45 volumio volumio[1256]: Updating browse sources language Feb 28 23:35:45 volumio volumio[1256]: Cannot find translation for sourceMedia Servers Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 23:35:45 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 28 23:35:45 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Feb 28 23:35:45 volumio systemd[1]: Starting Spotify Daemon... Feb 28 23:35:45 volumio systemd[1]: Started Spotify Daemon. Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::initPlayerControls Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 28 23:35:45 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 23:35:45 volumio volumio[1256]: info: BOOT COMPLETED Feb 28 23:35:45 volumio volumio[1256]: [Metrics] CommandRouter: 8s 524.33ms Feb 28 23:35:45 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [SPTF] 23:35:45.366 I [offline_authorizer.cpp:297] Unable to login offline: no such user Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [SPTF] 23:35:45.367 I [ap:1752] Connecting to AP ap.spotify.com:4070 Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [SPTF] 23:35:45.442 I [ap:1226] Connected to AP: 34.158.255.62:4070 Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [SPTF] 23:35:45.707 E [ap:3953] Connection error: 409 Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [WARN] Login failed: User needs a premium account Feb 28 23:35:45 volumio volumio[1372]: 2025-02-28 23:35:45 [ERR ] Could not get the playlist container. Feb 28 23:35:45 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Feb 28 23:35:45 volumio systemd[1]: Unit spop.service entered failed state. Feb 28 23:35:46 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:50 volumio volumio[1256]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 28 23:35:50 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Feb 28 23:35:50 volumio volumio[1256]: info: CoreCommandRouter::Close All Modals sent Feb 28 23:35:50 volumio volumio[1256]: Express server listening on port 3000 Feb 28 23:35:50 volumio volumio[1256]: [Metrics] WebUI: 14s 598.55ms Feb 28 23:35:50 volumio volumio[1256]: Volumio Calling Home Feb 28 23:35:50 volumio volumio[1256]: info: CoreStateMachine::resetVolumioState Feb 28 23:35:50 volumio volumio[1256]: info: CoreStateMachine::getcurrentVolume Feb 28 23:35:50 volumio volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 23:35:50 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 23:35:50 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 23:35:51 volumio volumio[1256]: info: Setting Device type: Raspberry PI Feb 28 23:35:51 volumio volumio[1256]: info: SpopD Daemon Started Feb 28 23:35:51 volumio volumio[1256]: info: MPD running with PID788 Feb 28 23:35:51 volumio volumio[1256]: ,establishing connection Feb 28 23:35:51 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 23:35:51 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:51 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 23:35:51 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:51 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:51 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:51 volumio volumio[1256]: info: VolumeController:: Volume=84 Mute =false Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::pushState Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::updateTrackBlock Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrackBlock Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 23:35:51 volumio volumio[1256]: info: Volumio called home Feb 28 23:35:51 volumio volumio[1256]: info: Reloading queue from file Feb 28 23:35:51 volumio volumio[1256]: error: updateQueue error: null Feb 28 23:35:51 volumio volumio[1256]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 28 23:35:51 volumio wpa_supplicant[1038]: wlan0: Failed to initiate sched scan Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::setRepeat true single undefined Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::pushState Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::setRandom false Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::pushState Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 23:35:51 volumio volumio[1256]: info: VolumeController:: Volume=84 Mute =false Feb 28 23:35:51 volumio volumio[1256]: info: CoreStateMachine::pushState Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioPushState Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 23:35:51 volumio volumio[1256]: info: Starting Shairport Sync Feb 28 23:35:51 volumio volumio[1256]: info: Starting Shairport Sync Feb 28 23:35:51 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 23:35:51 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:51 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 23:35:51 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:51 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 28 23:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 23:35:51 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 23:35:51 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 28 23:35:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 23:35:51 volumio volumio[1256]: Discovery: adding fed8e6c3-fd48-47d4-93c8-c5fc6e02a9bb Feb 28 23:35:51 volumio volumio[1256]: info: mDNS: Found device Volumio Feb 28 23:35:51 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:51 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Feb 28 23:35:51 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:51 volumio sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 28 23:35:51 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:51 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 28 23:35:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 28 23:35:51 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:52 volumio volumio[1256]: info: Shairport-Sync Started Feb 28 23:35:52 volumio volumio[1256]: Error adding Membership: Error: addMembership EINVAL Feb 28 23:35:52 volumio volumio[1256]: info: Shairport-Sync Started Feb 28 23:35:52 volumio volumio[1256]: info: Upmpdcli Daemon Started Feb 28 23:35:52 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Feb 28 23:35:52 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:52 volumio volumio[1256]: info: CoreCommandRouter::volumioGetState Feb 28 23:35:52 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 28 23:35:53 volumio volumio[1256]: info: Adding plugin bluetooth to MyMusic Plugins Feb 28 23:35:53 volumio volumio[1256]: info: Adding plugin cd_controller to MyMusic Plugins Feb 28 23:35:53 volumio volumio[1256]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 28 23:35:53 volumio volumio[1256]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 28 23:35:53 volumio volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 28 23:35:54 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 23:35:54 volumio volumio[1256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 23:35:54 volumio volumio[1256]: info: Starting MyVolumio Remote Streaming Endpoints Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 28 23:35:54 volumio volumio[1256]: info: Streaming services startup Feb 28 23:35:54 volumio volumio[1256]: info: Starting Streaming Daemon Feb 28 23:35:54 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 23:35:54 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 23:35:54 volumio volumio[1256]: info: MyVolumio not started Feb 28 23:35:54 volumio volumio[1256]: info: Initializing device activation check Feb 28 23:35:54 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 28 23:35:54 volumio volumio[1256]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 28 23:35:54 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 28 23:35:54 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Feb 28 23:35:54 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 28 23:35:54 volumio volumio-streaming-daemon[1479]: ############################ Feb 28 23:35:54 volumio volumio-streaming-daemon[1479]: # Volumio Streaming Daemon # Feb 28 23:35:54 volumio volumio-streaming-daemon[1479]: # Running on port 7777 # Feb 28 23:35:54 volumio volumio-streaming-daemon[1479]: ############################ Feb 28 23:35:54 volumio volumio-streaming-daemon[1479]: Environment: production Feb 28 23:35:55 volumio volumio[1256]: info: CoreCommandRouter::volumioSeek Feb 28 23:35:55 volumio volumio[1256]: info: CoreStateMachine::seek Feb 28 23:35:55 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:55 volumio volumio[1256]: info: TRACKBLOCK {"uri":"spotify:track:1300POeOaj8qho30FRHjgf","service":"spop","name":"Cornflake Girl","artist":"Tori Amos","album":"Under the Pink","type":"song","duration":305,"tracknumber":8,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e925ce40de481ef6f936897","samplerate":"320Kbps","bitdepth":"16 bit","trackType":"spotify"} Feb 28 23:35:55 volumio volumio[1256]: info: CoreStateMachine::startPlaybackTimer Feb 28 23:35:55 volumio volumio[1256]: info: CorePlayQueue::getTrack 0 Feb 28 23:35:55 volumio volumio[1256]: info: [1740785755301] ControllerSpop::seek to 69000 Feb 28 23:35:55 volumio volumio[1256]: info: [1740785755302] ControllerSpop::sendSpopCommand Feb 28 23:35:55 volumio volumio[1256]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 23:35:55 volumio volumio[1256]: TypeError: Cannot read property 'then' of undefined Feb 28 23:35:55 volumio volumio[1256]: at ControllerSpop.sendSpopCommand (/data/plugins/music_service/spop/index.js:1551:10) Feb 28 23:35:55 volumio volumio[1256]: at ControllerSpop.seek (/data/plugins/music_service/spop/index.js:2230:17) Feb 28 23:35:55 volumio volumio[1256]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1108:22) Feb 28 23:35:55 volumio volumio[1256]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1341:28) Feb 28 23:35:55 volumio volumio[1256]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:140:33) Feb 28 23:35:55 volumio volumio[1256]: at emitTwo (events.js:126:13) Feb 28 23:35:55 volumio volumio[1256]: at Socket.emit (events.js:214:7) Feb 28 23:35:55 volumio volumio[1256]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 28 23:35:55 volumio volumio[1256]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Feb 28 23:35:55 volumio volumio[1256]: at process._tickCallback (internal/process/next_tick.js:180:9) Feb 28 23:35:55 volumio volumio[1256]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 23:35:56 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 23:34 Feb 28 23:35:56 volumio sudo[1497]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"