-- Logs begin at Sat 2024-10-12 06:11:44 UTC, end at Sat 2024-10-12 06:16:02 UTC. -- Oct 12 06:15:01 volumio volumio[1244]: info: Loading plugin "network"... Oct 12 06:15:01 volumio volumio[1244]: info: Refreshing Cached IP Addresses Oct 12 06:15:01 volumio volumio[1244]: info: Loading plugin "services"... Oct 12 06:15:01 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 06:15:01 volumio volumio[1244]: info: Loading plugin "alsa_controller"... Oct 12 06:15:01 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:01 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:01 volumio sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 06:15:02 volumio sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:02 volumio sudo[1264]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:02 volumio volumio[1244]: info: Loading plugin "wizard"... Oct 12 06:15:02 volumio volumio[1244]: info: Loading plugin "volumio_command_line_client"... Oct 12 06:15:02 volumio volumio[1244]: info: Loading plugin "upnp"... Oct 12 06:15:02 volumio volumio[1244]: info: [1728713702689] Starting Upmpd Daemon Oct 12 06:15:02 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 06:15:02 volumio volumio[1244]: info: Loading plugin "my_music"... Oct 12 06:15:02 volumio volumio[1244]: info: Loading plugin "mpd"... Oct 12 06:15:04 volumio volumio[1244]: info: Loading plugin "upnp_browser"... Oct 12 06:15:06 volumio volumio[1244]: info: Loading plugin "networkfs"... Oct 12 06:15:06 volumio volumio-remote-updater[460]: [2024-10-12 06:15:06] [connect] Successful connection Oct 12 06:15:06 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 06:15:06 volumio volumio[1244]: info: Loading plugin "alarm-clock"... Oct 12 06:15:07 volumio volumio[1244]: info: Loading plugin "airplay_emulation"... Oct 12 06:15:08 volumio volumio[1244]: info: Starting Shairport Sync Oct 12 06:15:08 volumio volumio[1244]: info: Loading plugin "last_100"... Oct 12 06:15:08 volumio volumio[1244]: info: Loading plugin "webradio"... Oct 12 06:15:08 volumio volumio[1244]: info: Loading plugin "i2s_dacs"... Oct 12 06:15:08 volumio volumio[1244]: info: I2S DAC not set, start Auto-detection Oct 12 06:15:08 volumio volumio[1244]: info: Loading plugin "volumiodiscovery"... Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 06:15:08 volumio node[1244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 12 06:15:08 volumio node[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 06:15:08 volumio node[1244]: *** WARNING *** For more information see Oct 12 06:15:08 volumio node[1244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 06:15:08 volumio node[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 06:15:08 volumio node[1244]: *** WARNING *** For more information see Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** For more information see Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 12 06:15:08 volumio volumio[1244]: *** WARNING *** For more information see Oct 12 06:15:08 volumio volumio[1244]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 12 06:15:08 volumio volumio[1244]: Discovery: StartAdv! undefined Oct 12 06:15:08 volumio volumio[1244]: Discovery: Started advertising... Volumio-2 - undefined Oct 12 06:15:08 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 12 06:15:08 volumio volumio[1244]: info: Loading plugin "gpio-buttons"... Oct 12 06:15:10 volumio volumio[1244]: info: GPIO-Buttons initialized Oct 12 06:15:10 volumio volumio[1244]: info: Loading plugin "outputs"... Oct 12 06:15:11 volumio volumio[1244]: info: Loading plugin "albumart"... Oct 12 06:15:11 volumio volumio[1244]: info: Plugin example_plugin is not enabled Oct 12 06:15:11 volumio volumio[1244]: info: Loading plugin "inputs"... Oct 12 06:15:11 volumio volumio[1244]: info: Loading plugin "updater_comm"... Oct 12 06:15:12 volumio volumio[1244]: info: Plugin mpdemulation is not enabled Oct 12 06:15:12 volumio volumio[1244]: info: Loading plugin "rest_api"... Oct 12 06:15:12 volumio volumio[1244]: info: Loading plugin "websocket"... Oct 12 06:15:13 volumio volumio[1244]: info: Loading plugin "spop"... Oct 12 06:15:14 volumio volumio[1244]: Forking 1 albumart workers Oct 12 06:15:19 volumio volumio[1244]: info: Loading plugin "volspotconnect2"... Oct 12 06:15:21 volumio volumio-remote-updater[460]: [2024-10-12 06:15:21] [connect] Successful connection Oct 12 06:15:21 volumio volumio[1244]: Starting albumart workers Oct 12 06:15:24 volumio volumio[1244]: info: ___________ START PLUGINS ___________ Oct 12 06:15:24 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:24 volumio volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 06:15:24 volumio volumio[1244]: info: [1728713724408] CoreMusicLibrary::Adding element Media Servers Oct 12 06:15:24 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:15:25 volumio volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 06:15:25 volumio volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 06:15:25 volumio volumio[1244]: info: [1728713725359] CoreMusicLibrary::Adding element Last_100 Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 12 06:15:25 volumio volumio[1244]: info: [1728713725402] CoreMusicLibrary::Adding element Webradio Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 06:15:25 volumio volumio[1244]: info: GPIO-Buttons: Reading config and creating triggers... Oct 12 06:15:25 volumio volumio[1244]: info: GPIO-Buttons: shutdown on pin 3 Oct 12 06:15:25 volumio volumio[1244]: [SpotifyConnect] Creating VLS config file Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:25 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:25 volumio volumio[1244]: [SpotifyConnect] Starting metadata listener Oct 12 06:15:25 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Oct 12 06:15:25 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:26 volumio systemd[1]: Starting Spotify Daemon... Oct 12 06:15:26 volumio volumio[1244]: info: ------------------------------------------- Oct 12 06:15:26 volumio systemd[1]: Started Spotify Daemon. Oct 12 06:15:26 volumio volumio[1244]: info: ----- MyVolumio plugins startup ---- Oct 12 06:15:26 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:26 volumio volumio[1244]: info: ------------------------------------------- Oct 12 06:15:26 volumio volumio[1244]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.240 I [offline_authorizer.cpp:297] Unable to login offline: no such user Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.250 I [ap:1752] Connecting to AP ap.spotify.com:4070 Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.327 I [ap:1226] Connected to AP: 104.199.241.202:4070 Oct 12 06:15:26 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 12 06:15:26 volumio volumio[1244]: info: Loading i18n strings for locale ja Oct 12 06:15:26 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:26 volumio volumio[1244]: Updating browse sources language Oct 12 06:15:26 volumio volumio[1244]: Cannot find translation for sourceMedia Servers Oct 12 06:15:26 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.648 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.651 E [ap:3953] Connection error: 112 Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.653 I [ap:1752] Connecting to AP ap.spotify.com:443 Oct 12 06:15:26 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 12 06:15:26 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:26 volumio volumio[1328]: 2024-10-12 06:15:26 [SPTF] 06:15:26.716 I [ap:1226] Connected to AP: 104.199.241.202:443 Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::initPlayerControls Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.256 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.259 E [ap:3953] Connection error: 112 Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.261 I [ap:1752] Connecting to AP ap.spotify.com:80 Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.311 I [ap:1226] Connected to AP: 104.199.241.202:80 Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:27 volumio volumio[1244]: info: BOOT COMPLETED Oct 12 06:15:27 volumio volumio[1244]: [Metrics] CommandRouter: 40s 443.57ms Oct 12 06:15:27 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.818 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.819 E [ap:3953] Connection error: 112 Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.822 I [ap:1752] Connecting to AP ap.spotify.com:4070 Oct 12 06:15:27 volumio volumio[1328]: 2024-10-12 06:15:27 [SPTF] 06:15:27.882 I [ap:1226] Connected to AP: 104.199.241.202:4070 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.487 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.492 E [ap:3953] Connection error: 112 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.497 I [ap:1752] Connecting to AP ap.spotify.com:443 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.547 I [ap:1226] Connected to AP: 104.199.241.202:443 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.769 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.774 E [ap:3953] Connection error: 112 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.779 I [ap:1752] Connecting to AP ap.spotify.com:80 Oct 12 06:15:28 volumio volumio[1328]: 2024-10-12 06:15:28 [SPTF] 06:15:28.854 I [ap:1226] Connected to AP: 104.199.241.202:80 Oct 12 06:15:29 volumio volumio[1328]: 2024-10-12 06:15:29 [SPTF] 06:15:29.148 E [ap:1694] AP Socket Error: Software caused connection abort (103) Oct 12 06:15:29 volumio volumio[1328]: 2024-10-12 06:15:29 [SPTF] 06:15:29.152 E [ap:3953] Connection error: 112 Oct 12 06:15:29 volumio volumio[1328]: 2024-10-12 06:15:29 [WARN] Login failed: Cannot connect to Spotify Oct 12 06:15:29 volumio volumio[1328]: 2024-10-12 06:15:29 [ERR ] Could not get the playlist container. Oct 12 06:15:29 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Oct 12 06:15:29 volumio systemd[1]: Unit spop.service entered failed state. Oct 12 06:15:33 volumio volumio[1244]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 12 06:15:33 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Oct 12 06:15:33 volumio volumio[1244]: info: CoreCommandRouter::Close All Modals sent Oct 12 06:15:33 volumio volumio[1244]: Express server listening on port 3000 Oct 12 06:15:33 volumio volumio[1244]: [Metrics] WebUI: 48s 379.54ms Oct 12 06:15:33 volumio volumio[1244]: Volumio Calling Home Oct 12 06:15:33 volumio volumio[1244]: info: GPIO-Buttons started Oct 12 06:15:33 volumio volumio[1244]: info: CoreStateMachine::resetVolumioState Oct 12 06:15:33 volumio volumio[1244]: info: CoreStateMachine::getcurrentVolume Oct 12 06:15:33 volumio volumio[1244]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 06:15:34 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:34 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 12 06:15:34 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 06:15:34 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:34 volumio sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 06:15:34 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:34 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:34 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:34 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 12 06:15:34 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 12 06:15:35 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 12 06:15:36 volumio volumio-remote-updater[460]: [2024-10-12 06:15:36] [connect] Successful connection Oct 12 06:15:36 volumio volumio[1244]: info: Volumio called home Oct 12 06:15:36 volumio volumio[1244]: info: Setting Device type: Raspberry PI Oct 12 06:15:36 volumio volumio[1244]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 12 06:15:36 volumio volumio[1244]: [Metrics] SpotifyConnect: 10s 938.82ms Oct 12 06:15:36 volumio volumio[1244]: info: SpopD Daemon Started Oct 12 06:15:36 volumio volumio[1244]: error: Cannot initialize MPD Connection: MPD is not running Oct 12 06:15:36 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 12 06:15:36 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:37 volumio volumio[1244]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 12 06:15:37 volumio volumio[1244]: info: VolumeController:: Volume=0 Mute =false Oct 12 06:15:37 volumio volumio[1244]: info: CoreStateMachine::pushState Oct 12 06:15:37 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:37 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 12 06:15:37 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:15:37 volumio volumio[1244]: info: CoreStateMachine::updateTrackBlock Oct 12 06:15:37 volumio volumio[1244]: info: CorePlayQueue::getTrackBlock Oct 12 06:15:37 volumio volumio[1244]: info: CoreCommandRouter::volumioRetrievevolume Oct 12 06:15:38 volumio volumio-remote-updater[460]: [2024-10-12 06:15:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1728713736 101 Oct 12 06:15:38 volumio volumio[1244]: info: Reloading queue from file Oct 12 06:15:39 volumio volumio[1244]: info: CoreStateMachine::setRepeat null single undefined Oct 12 06:15:39 volumio volumio[1244]: info: CoreStateMachine::pushState Oct 12 06:15:39 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:15:39 volumio volumio[1244]: info: CoreStateMachine::setRandom null Oct 12 06:15:39 volumio volumio[1244]: info: CoreStateMachine::pushState Oct 12 06:15:39 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:15:39 volumio volumio[1244]: info: VolumeController:: Volume=0 Mute =false Oct 12 06:15:39 volumio volumio[1244]: info: CoreStateMachine::pushState Oct 12 06:15:39 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::volumioPushState Oct 12 06:15:39 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 12 06:15:39 volumio volumio[1244]: info: Starting Shairport Sync Oct 12 06:15:39 volumio volumio[1244]: info: Starting Shairport Sync Oct 12 06:15:39 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 06:15:39 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:40 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 12 06:15:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 06:15:40 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:40 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 12 06:15:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 06:15:40 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 12 06:15:40 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 12 06:15:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 12 06:15:40 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 12 06:15:40 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 12 06:15:40 volumio sudo[1404]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 12 06:15:40 volumio volumio[1244]: info: Adding plugin bluetooth to MyMusic Plugins Oct 12 06:15:40 volumio volumio[1244]: info: Adding plugin cd_controller to MyMusic Plugins Oct 12 06:15:40 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 12 06:15:40 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:40 volumio volumio[1244]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 12 06:15:40 volumio volumio[1244]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 12 06:15:40 volumio volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 12 06:15:44 volumio volumio[1244]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 12 06:15:45 volumio volumio[1244]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 12 06:15:45 volumio volumio[1244]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 12 06:15:45 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:45 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:45 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:45 volumio volumio[1244]: info: Starting MyVolumio Remote Streaming Endpoints Oct 12 06:15:45 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:45 volumio volumio[1244]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 12 06:15:45 volumio volumio[1244]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 12 06:15:45 volumio volumio[1244]: info: Streaming services startup Oct 12 06:15:45 volumio volumio[1244]: info: Starting Streaming Daemon Oct 12 06:15:45 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 12 06:15:45 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:45 volumio volumio[1244]: info: MyVolumio not started Oct 12 06:15:45 volumio volumio[1244]: info: Initializing device activation check Oct 12 06:15:45 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:45 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 12 06:15:45 volumio volumio[1244]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 12 06:15:45 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 12 06:15:45 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 12 06:15:45 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:46 volumio volumio-streaming-daemon[1455]: ############################ Oct 12 06:15:46 volumio volumio-streaming-daemon[1455]: # Volumio Streaming Daemon # Oct 12 06:15:46 volumio volumio-streaming-daemon[1455]: # Running on port 7777 # Oct 12 06:15:46 volumio volumio-streaming-daemon[1455]: ############################ Oct 12 06:15:46 volumio volumio-streaming-daemon[1455]: Environment: Oct 12 06:15:46 volumio volumio[1244]: info: Connecting to daemon Oct 12 06:15:46 volumio volumio[1244]: Discovery: adding 36aa2c7b-10a4-4f7f-8ba6-1d74a5ca46f1 Oct 12 06:15:46 volumio volumio[1244]: info: mDNS: Found device Volumio-2 Oct 12 06:15:46 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 12 06:15:46 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:46 volumio volumio[1244]: info: Upmpdcli Daemon Started Oct 12 06:15:46 volumio volumio[1244]: info: Shairport-Sync Started Oct 12 06:15:46 volumio volumio[1244]: Error adding Membership: Error: addMembership EINVAL Oct 12 06:15:46 volumio volumio[1244]: info: Shairport-Sync Started Oct 12 06:15:46 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:47 volumio volumio[1244]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Oct 12 06:15:47 volumio volumio[1244]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Oct 12 06:15:47 volumio volumio[1244]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 12 06:15:47 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 12 06:15:47 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 12 06:15:47 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:48 volumio sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 06:15:48 volumio sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:48 volumio sudo[1463]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:48 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 06:15:48 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:48 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:49 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 12 06:15:49 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:49 volumio volumio-streaming-daemon[1455]: Environment: Oct 12 06:15:49 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 12 06:15:49 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:49 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 12 06:15:49 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root Oct 12 06:15:50 volumio volumio[1244]: info: Fetching Streaming Services browse cache Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::volumioGetState Oct 12 06:15:52 volumio volumio[1244]: info: CorePlayQueue::getTrack 0 Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 12 06:15:52 volumio volumio[1244]: info: Listing playlists Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 06:15:52 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 12 06:15:55 volumio volumio[1244]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Oct 12 06:15:55 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:55 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 12 06:15:55 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 12 06:15:56 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:56 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:56 volumio volumio[1244]: info: Retrieving Cloud Streaming UI Oct 12 06:15:56 volumio volumio[1244]: info: Getting Tidal Cloud Configuration Oct 12 06:15:56 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:56 volumio volumio[1244]: info: Getting Qobuz Cloud Configuration Oct 12 06:15:56 volumio volumio[1244]: info: Asking plugin for UI Config Oct 12 06:15:56 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:56 volumio volumio[1244]: info: Getting Spotify Cloud Configuration Oct 12 06:15:56 volumio volumio[1244]: info: Asking plugin for UI Config Oct 12 06:15:56 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:57 volumio volumio[1244]: info: Saving Spotify Acccount Oct 12 06:15:57 volumio volumio[1244]: info: Got Tidal Cloud Configuration Oct 12 06:15:57 volumio volumio[1244]: info: Got it Oct 12 06:15:57 volumio volumio[1244]: info: Got it Oct 12 06:15:57 volumio volumio[1244]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 12 06:15:57 volumio volumio[1244]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::volumioGetBrowseSources Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 12 06:15:57 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 12 06:15:59 volumio volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 12 06:15:59 volumio volumio[1244]: verbose: ControllerMpd::sendMpdCommand update Oct 12 06:15:59 volumio volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 06:15:59 volumio volumio[1244]: TypeError: Cannot read property 'then' of undefined Oct 12 06:15:59 volumio volumio[1244]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Oct 12 06:15:59 volumio volumio[1244]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1838:15) Oct 12 06:15:59 volumio volumio[1244]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Oct 12 06:15:59 volumio volumio[1244]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:911:26) Oct 12 06:15:59 volumio volumio[1244]: at emitTwo (events.js:126:13) Oct 12 06:15:59 volumio volumio[1244]: at Socket.emit (events.js:214:7) Oct 12 06:15:59 volumio volumio[1244]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 12 06:15:59 volumio volumio[1244]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 12 06:15:59 volumio volumio[1244]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 12 06:15:59 volumio volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 06:16:02 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-12 06:15 Oct 12 06:16:02 volumio sudo[1519]: 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="537e3f50b5a8aa9bdf42f94adc80b348609b5c47" VOLUMIO_BE_VERSION="d7e6c614214d5dc616fddcbcfefc47e1990da7b1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 8 12:10:14 CEST 2021" VOLUMIO_VERSION="2.909" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3aea8955ace41c92b011ae3a243c3f0d"