-- 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"