-- Logs begin at Thu 2025-03-06 14:59:57 UTC, end at Thu 2025-03-06 17:59:28 UTC. -- Mar 06 17:58:02 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:02 volumio1 volumio[1104]: info: CorePlayQueue::getTrack 3 Mar 06 17:58:02 volumio1 volumio[1104]: info: CorePlayQueue::getTrack 4 Mar 06 17:58:02 volumio1 volumio[1104]: info: Prefetching next song Mar 06 17:58:02 volumio1 volumio[1104]: info: Doing Prefetch in UPNP Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::sendMpdCommand add "http://192.168.0.27:8200/MediaItems/31010.mp3" Mar 06 17:58:02 volumio1 volumio[1104]: info: sending command... Mar 06 17:58:02 volumio1 volumio[1104]: info: Mar 06 17:58:02 volumio1 volumio[1104]: ---------------------------- MPD announces system playlist update Mar 06 17:58:02 volumio1 volumio[1104]: info: Ignoring MPD Status Update Mar 06 17:58:02 volumio1 volumio[1104]: info: parsing response... Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::sendMpdCommand consume 1 Mar 06 17:58:02 volumio1 volumio[1104]: info: sending command... Mar 06 17:58:02 volumio1 volumio[1104]: info: Mar 06 17:58:02 volumio1 volumio[1104]: ---------------------------- MPD announces state update: options Mar 06 17:58:02 volumio1 volumio[1104]: info: ------------------------------ 9ms Mar 06 17:58:02 volumio1 volumio[1104]: info: parsing response... Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::getState Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::sendMpdCommand status Mar 06 17:58:02 volumio1 volumio[1104]: info: sending command... Mar 06 17:58:02 volumio1 volumio[1104]: info: parsing response... Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::parseState Mar 06 17:58:02 volumio1 volumio[1104]: info: ControllerMpd::pushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::servicePushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::syncState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:58:02 volumio1 volumio[1104]: info: CorePlayQueue::getTrack 3 Mar 06 17:58:02 volumio1 volumio[1104]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 06 17:58:02 volumio1 volumio[1104]: info: CURRENT POSITION 3 Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::syncState stateService stop Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::syncState currentStatus play Mar 06 17:58:02 volumio1 volumio[1104]: info: CURRENT POSITION 3 Mar 06 17:58:02 volumio1 volumio[1104]: info: CURRENT POSITION 4 Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:58:02 volumio1 sudo[8023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:58:02 volumio1 sudo[8023]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:02 volumio1 volumio[1104]: info: Prefetch done, skipping queuing Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:58:02 volumio1 sudo[8023]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:58:02 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:58:02 volumio1 sudo[8028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:58:02 volumio1 sudo[8028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:02 volumio1 sudo[8028]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:02 volumio1 sudo[8037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:58:02 volumio1 volumio[1104]: info: ------------------------------ 152ms Mar 06 17:58:02 volumio1 sudo[8037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:02 volumio1 sudo[8037]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:10 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:17 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:25 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:26 volumio1 volumio[1104]: error: message=Cannot read property '0' of undefined, stack=TypeError: Cannot read property '0' of undefined Mar 06 17:58:26 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:83:67 Mar 06 17:58:26 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:629:21 Mar 06 17:58:26 volumio1 volumio[1104]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:303:18) Mar 06 17:58:26 volumio1 volumio[1104]: at emitOne (events.js:116:13) Mar 06 17:58:26 volumio1 volumio[1104]: at Parser.emit (events.js:211:7) Mar 06 17:58:26 volumio1 volumio[1104]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:261:26) Mar 06 17:58:26 volumio1 volumio[1104]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 06 17:58:26 volumio1 volumio[1104]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 06 17:58:26 volumio1 volumio[1104]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 06 17:58:26 volumio1 volumio[1104]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 06 17:58:26 volumio1 volumio[1104]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:322:31) Mar 06 17:58:26 volumio1 volumio[1104]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 06 17:58:26 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:628:24 Mar 06 17:58:26 volumio1 volumio[1104]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:463:26) Mar 06 17:58:26 volumio1 volumio[1104]: at Request.self.callback (/volumio/node_modules/unirest/node_modules/request/request.js:187:22) Mar 06 17:58:26 volumio1 volumio[1104]: at emitTwo (events.js:126:13) Mar 06 17:58:33 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:36 volumio1 volumio[1104]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: Output device has changed, restarting MPD Mar 06 17:58:36 volumio1 sudo[8285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 06 17:58:36 volumio1 volumio[1104]: info: Output device has changed, restarting Shairport Sync Mar 06 17:58:36 volumio1 volumio[1104]: [SpotifyConnect] Creating VLS config file Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: [SpotifyConnect] Creating VLS config file Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 sudo[8285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 sudo[8288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 06 17:58:36 volumio1 sudo[8285]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:36 volumio1 sudo[8288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:58:36 volumio1 systemd[1]: Stopping Music Player Daemon... Mar 06 17:58:36 volumio1 volumio[1104]: info: Device 1 does not have any Mixer Control Available, setting a softvol device Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 06 17:58:36 volumio1 volumio[1104]: info: Updating Volume Controller Parameters: Device: 1 Name: snd_allo_digione Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:58:36 volumio1 volumio[1104]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 06 17:58:36 volumio1 sudo[8306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:58:36 volumio1 systemd[1]: Starting Music Player Daemon... Mar 06 17:58:36 volumio1 sudo[8306]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:36 volumio1 sudo[8306]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:36 volumio1 sudo[8313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 06 17:58:36 volumio1 volumio[1104]: info: MPD Permissions set Mar 06 17:58:36 volumio1 sudo[8313]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 systemd[1]: Stopping Volspotconnect2 Daemon... Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:58:36 volumio1 systemd[1]: Starting Volspotconnect2 Daemon... Mar 06 17:58:36 volumio1 systemd[1]: Started Volspotconnect2 Daemon. Mar 06 17:58:36 volumio1 sudo[8313]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:36 volumio1 volumio[8323]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Mar 06 17:58:36 volumio1 volumio[8323]: Reading Config from "/tmp/volspotify.toml" Mar 06 17:58:36 volumio1 volumio[8323]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[8323]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 06 17:58:36 volumio1 volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 06 17:58:36 volumio1 volumio[1104]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 06 17:58:37 volumio1 volumio[1104]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 06 17:58:37 volumio1 sudo[8334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 06 17:58:37 volumio1 sudo[8334]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:37 volumio1 sudo[8336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Mar 06 17:58:37 volumio1 sudo[8336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:37 volumio1 systemd[1]: Stopping ShairportSync AirTunes receiver... Mar 06 17:58:37 volumio1 systemd[1]: Stopping Volspotconnect2 Daemon... Mar 06 17:58:37 volumio1 systemd[1]: Starting ShairportSync AirTunes receiver... Mar 06 17:58:37 volumio1 systemd[1]: Started ShairportSync AirTunes receiver. Mar 06 17:58:37 volumio1 sudo[8334]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:37 volumio1 volumio[1104]: error: Upnp client error: Error: This socket has been ended by the other party Mar 06 17:58:37 volumio1 shairport-sync[8347]: Successful Startup Mar 06 17:58:37 volumio1 volumio[1104]: info: Shairport-Sync Started Mar 06 17:58:37 volumio1 systemd[1]: Starting Volspotconnect2 Daemon... Mar 06 17:58:37 volumio1 systemd[1]: Started Volspotconnect2 Daemon. Mar 06 17:58:37 volumio1 sudo[8336]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:37 volumio1 volumio[8352]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Mar 06 17:58:37 volumio1 volumio[8352]: Reading Config from "volspotify.toml" Mar 06 17:58:37 volumio1 volumio[8352]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Mar 06 17:58:37 volumio1 volumio[1104]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 06 17:58:37 volumio1 volumio[8352]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Mar 06 17:58:37 volumio1 systemd[1]: Started Music Player Daemon. Mar 06 17:58:37 volumio1 sudo[8288]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:37 volumio1 volumio[1104]: info: Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreStateMachine::getcurrentVolume Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:58:39 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:58:39 volumio1 sudo[8366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:58:39 volumio1 sudo[8366]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:58:39 volumio1 sudo[8366]: pam_unix(sudo:session): session closed for user root Mar 06 17:58:41 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:42 volumio1 volumio[1104]: info: Checking Spotify Web API Mar 06 17:58:49 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:58:56 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:59:04 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:59:12 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:59:16 volumio1 volumio[1104]: error: message=Cannot read property '0' of undefined, stack=TypeError: Cannot read property '0' of undefined Mar 06 17:59:16 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:83:67 Mar 06 17:59:16 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:629:21 Mar 06 17:59:16 volumio1 volumio[1104]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:303:18) Mar 06 17:59:16 volumio1 volumio[1104]: at emitOne (events.js:116:13) Mar 06 17:59:16 volumio1 volumio[1104]: at Parser.emit (events.js:211:7) Mar 06 17:59:16 volumio1 volumio[1104]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:261:26) Mar 06 17:59:16 volumio1 volumio[1104]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 06 17:59:16 volumio1 volumio[1104]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 06 17:59:16 volumio1 volumio[1104]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 06 17:59:16 volumio1 volumio[1104]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 06 17:59:16 volumio1 volumio[1104]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:322:31) Mar 06 17:59:16 volumio1 volumio[1104]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 06 17:59:16 volumio1 volumio[1104]: at /volumio/app/plugins/music_service/upnp_browser/index.js:628:24 Mar 06 17:59:16 volumio1 volumio[1104]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:463:26) Mar 06 17:59:16 volumio1 volumio[1104]: at Request.self.callback (/volumio/node_modules/unirest/node_modules/request/request.js:187:22) Mar 06 17:59:16 volumio1 volumio[1104]: at emitTwo (events.js:126:13) Mar 06 17:59:19 volumio1 volumio[1104]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 06 17:59:19 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 06 17:59:19 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:59:19 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 06 17:59:19 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:59:19 volumio1 volumio[1104]: info: Output device has changed, restarting MPD Mar 06 17:59:20 volumio1 sudo[8462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 06 17:59:20 volumio1 volumio[1104]: info: Output device has changed, restarting Shairport Sync Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Creating VLS config file Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 sudo[8462]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Creating VLS config file Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 sudo[8465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 06 17:59:20 volumio1 sudo[8462]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] SpotifyConnect Daemon config issues! Mar 06 17:59:20 volumio1 sudo[8465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] volspotify config error: [ '#initial-volume = undefined', [length]: 1 ] Mar 06 17:59:20 volumio1 systemd[1]: Stopping Music Player Daemon... Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:59:20 volumio1 systemd[1]: Starting Music Player Daemon... Mar 06 17:59:20 volumio1 volumio[1104]: info: Device 1 does not have any Mixer Control Available, setting a softvol device Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 06 17:59:20 volumio1 volumio[1104]: info: Updating Volume Controller Parameters: Device: 1 Name: snd_allo_digione Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:59:20 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 06 17:59:20 volumio1 sudo[8485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:59:20 volumio1 sudo[8485]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 sudo[8485]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:20 volumio1 volumio[1104]: info: MPD Permissions set Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 sudo[8490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 17:59:20 volumio1 sudo[8490]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 systemd[1]: Stopping Volspotconnect2 Daemon... Mar 06 17:59:20 volumio1 systemd[1]: Starting Volspotconnect2 Daemon... Mar 06 17:59:20 volumio1 systemd[1]: Started Volspotconnect2 Daemon. Mar 06 17:59:20 volumio1 sudo[8490]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 06 17:59:20 volumio1 volumio[8502]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Mar 06 17:59:20 volumio1 volumio[8502]: Reading Config from "/tmp/volspotify.toml" Mar 06 17:59:20 volumio1 volumio[8502]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 06 17:59:20 volumio1 volumio[1104]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 06 17:59:20 volumio1 volumio[8502]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 06 17:59:20 volumio1 volumio[1104]: error: Upnp client error: Error: This socket has been ended by the other party Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 06 17:59:20 volumio1 sudo[8510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Mar 06 17:59:20 volumio1 sudo[8510]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 sudo[8513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 06 17:59:20 volumio1 sudo[8513]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:20 volumio1 systemd[1]: Stopping Volspotconnect2 Daemon... Mar 06 17:59:20 volumio1 systemd[1]: Starting Volspotconnect2 Daemon... Mar 06 17:59:20 volumio1 systemd[1]: Started Volspotconnect2 Daemon. Mar 06 17:59:20 volumio1 sudo[8510]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:20 volumio1 systemd[1]: Stopping ShairportSync AirTunes receiver... Mar 06 17:59:20 volumio1 volumio[8522]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Mar 06 17:59:20 volumio1 volumio[8522]: Reading Config from "volspotify.toml" Mar 06 17:59:20 volumio1 volumio[8522]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Mar 06 17:59:20 volumio1 systemd[1]: Starting ShairportSync AirTunes receiver... Mar 06 17:59:20 volumio1 volumio[1104]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 06 17:59:20 volumio1 systemd[1]: Started ShairportSync AirTunes receiver. Mar 06 17:59:20 volumio1 sudo[8513]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:20 volumio1 shairport-sync[8528]: Successful Startup Mar 06 17:59:20 volumio1 volumio[1104]: info: Shairport-Sync Started Mar 06 17:59:20 volumio1 volumio[8522]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Mar 06 17:59:21 volumio1 systemd[1]: Started Music Player Daemon. Mar 06 17:59:21 volumio1 sudo[8465]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:21 volumio1 volumio[1104]: info: Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreStateMachine::getcurrentVolume Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreStateMachine::pushState Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreStateMachine::getState Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreCommandRouter::volumioPushState Mar 06 17:59:22 volumio1 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 17:59:22 volumio1 sudo[8542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 17:59:22 volumio1 sudo[8542]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 17:59:22 volumio1 sudo[8542]: pam_unix(sudo:session): session closed for user root Mar 06 17:59:26 volumio1 volumio[1104]: info: Checking Spotify Web API Mar 06 17:59:27 volumio1 volumio[1104]: info: CoreCommandRouter::volumioNext Mar 06 17:59:27 volumio1 volumio[1104]: info: CoreStateMachine::next Mar 06 17:59:27 volumio1 volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 17:59:27 volumio1 volumio[1104]: TypeError: Cannot read property 'next' of undefined Mar 06 17:59:27 volumio1 volumio[1104]: at CoreStateMachine.next (/volumio/app/statemachine.js:1231:18) Mar 06 17:59:27 volumio1 volumio[1104]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:113:27) Mar 06 17:59:27 volumio1 volumio[1104]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:251:36) Mar 06 17:59:27 volumio1 volumio[1104]: at emitTwo (events.js:126:13) Mar 06 17:59:27 volumio1 volumio[1104]: at Socket.emit (events.js:214:7) Mar 06 17:59:27 volumio1 volumio[1104]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 06 17:59:27 volumio1 volumio[1104]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 06 17:59:27 volumio1 volumio[1104]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 06 17:59:27 volumio1 volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 17:59:27 volumio1 wpa_supplicant[888]: wlan0: Failed to initiate sched scan Mar 06 17:59:28 volumio1 sudo[8558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-06 17:58 Mar 06 17:59:28 volumio1 sudo[8558]: 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="44b2fa85b4b3fa34b9dce1402ab445658a4a845a" VOLUMIO_FE_VERSION="a229f8b133df0eed141775431f02f040e5a51d20" VOLUMIO_BE_VERSION="adf0384995b68dee9fc3a8eedb9a69a90d236430" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Feb 18 19:31:59 CET 2019" VOLUMIO_VERSION="2.555" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="804eca9a7e1d9c2d73b735206b85b15d"