-- Logs begin at Tue 2024-11-26 09:54:27 UTC, end at Tue 2024-11-26 10:30:27 UTC. -- Nov 26 10:29:00 volumiopc1 volumio[27765]: info: Plugin mpdemulation is not enabled Nov 26 10:29:00 volumiopc1 volumio[27765]: info: Loading plugin "rest_api"... Nov 26 10:29:00 volumiopc1 volumio[27765]: info: Loading plugin "websocket"... Nov 26 10:29:00 volumiopc1 volumio[27765]: info: Loading plugin "lms"... Nov 26 10:29:00 volumiopc1 volumio[27765]: Forking 1 albumart workers Nov 26 10:29:00 volumiopc1 volumio[27765]: info: Loading plugin "music_services_shield"... Nov 26 10:29:01 volumiopc1 volumio[27765]: info: Applying required configuration parameters for plugin music_services_shield Nov 26 10:29:01 volumiopc1 volumio[27765]: info: Loading plugin "Systeminfo"... Nov 26 10:29:01 volumiopc1 volumio[27765]: Starting albumart workers Nov 26 10:29:01 volumiopc1 volumio[27765]: info: Loading i18n strings for locale de Nov 26 10:29:01 volumiopc1 volumio[27765]: Updating browse sources language Nov 26 10:29:01 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::initPlayerControls Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: Express server listening on port 3000 Nov 26 10:29:02 volumiopc1 volumio[27765]: [Metrics] WebUI: 13s 704.27ms Nov 26 10:29:02 volumiopc1 volumio[27765]: info: Setting Device type: x86 Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreStateMachine::resetVolumioState Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreStateMachine::getcurrentVolume Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:02 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:02 volumiopc1 volumio[27765]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 26 10:29:02 volumiopc1 volumio[27765]: info: Completed loading Core Plugins Nov 26 10:29:02 volumiopc1 volumio[27765]: info: Preparing to generate the ALSA configuration file Nov 26 10:29:02 volumiopc1 volumio[27765]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 10:29:02 volumiopc1 volumio[27765]: wlan0 Interface doesn't support scanning. Nov 26 10:29:02 volumiopc1 volumio[27765]: info: Cannot use regular scanning, forcing with ap-force Nov 26 10:29:02 volumiopc1 sudo[27826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 10:29:02 volumiopc1 sudo[27826]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:02 volumiopc1 sudo[27826]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:03 volumiopc1 volumio[27765]: command failed: No such device (-19) Nov 26 10:29:03 volumiopc1 volumio[27765]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 26 10:29:03 volumiopc1 volumio[27765]: command failed: No such device (-19) Nov 26 10:29:03 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1 Nov 26 10:29:03 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 Nov 26 10:29:03 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Reloading queue from file Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Asound.conf file unchanged, so no further update is needed Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Output device has changed, restarting MPD Nov 26 10:29:03 volumiopc1 sudo[27836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:03 volumiopc1 sudo[27836]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:03 volumiopc1 sudo[27836]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Output device has changed, restarting Shairport Sync Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:03 volumiopc1 sudo[27839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:03 volumiopc1 sudo[27839]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:03 volumiopc1 systemd[1]: musicservicesshield.service: Succeeded. Nov 26 10:29:03 volumiopc1 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 26 10:29:03 volumiopc1 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:03 volumiopc1 systemd[1]: Stopping Music Player Daemon... Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:03 volumiopc1 volumio[27765]: info: ___________ START PLUGINS ___________ Nov 26 10:29:03 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:03 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:03 volumiopc1 volumio[27765]: info: ControllerMpd::onStart: Initializing MPD Nov 26 10:29:03 volumiopc1 volumio[27765]: info: Creating MPD Configuration file Nov 26 10:29:03 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:03 volumiopc1 sudo[27846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:03 volumiopc1 sudo[27846]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:03 volumiopc1 sudo[27846]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:03 volumiopc1 volumio[27765]: info: [1732616943779] CoreMusicLibrary::Adding element Medienserver Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:03 volumiopc1 sudo[27844]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:03 volumiopc1 sudo[27844]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:03 volumiopc1 sudo[27844]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:03 volumiopc1 sudo[27849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:03 volumiopc1 sudo[27849]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:03 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:03 volumiopc1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 26 10:29:03 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:03 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:03 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:04 volumiopc1 volumio[27765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:04 volumiopc1 sudo[27854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:04 volumiopc1 sudo[27854]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:04 volumiopc1 volumio[27765]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:04 volumiopc1 sudo[27854]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:04 volumiopc1 volumio[27765]: info: [1732616944198] CoreMusicLibrary::Adding element Last_100 Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:04 volumiopc1 volumio[27765]: info: [1732616944201] CoreMusicLibrary::Adding element Webradio Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:29:04 volumiopc1 volumio[27765]: info: [squeezelite_mc] Starting proxy server... Nov 26 10:29:04 volumiopc1 volumio[27765]: info: [now-playing-config] Config is up to date. Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 10:29:04 volumiopc1 volumio[27765]: info: Discovery: Getting this device information Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioGetState Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:04 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 10:29:04 volumiopc1 sudo[27863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 10:29:04 volumiopc1 sudo[27863]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:04 volumiopc1 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 10:29:04 volumiopc1 squeezeboxserver[27687]: [24-11-26 10:28:53.3731] Slim::Networking::Async::Socket::UDP::mcast_add (48) Error: While adding multicast membership, UPnP may not work properly: No such device Nov 26 10:29:04 volumiopc1 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 10:29:04 volumiopc1 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 10:29:05 volumiopc1 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 10:29:05 volumiopc1 systemd[1]: Started Logitech Media Server Daemon. Nov 26 10:29:05 volumiopc1 sudo[27863]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:05 volumiopc1 volumio[27765]: info: Volumio Calling Home Nov 26 10:29:05 volumiopc1 sudo[27894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 26 10:29:05 volumiopc1 sudo[27894]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:06 volumiopc1 volumio[27765]: info: [squeezelite_mc] Proxy server started on port 33399 Nov 26 10:29:06 volumiopc1 volumio[27765]: info: [now-playing-app] App is listening on port 4004. Nov 26 10:29:06 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::setRepeat null single undefined Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::pushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::setRandom null Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::pushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::pushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreStateMachine::updateTrackBlock Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrackBlock Nov 26 10:29:07 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:07 volumiopc1 volumio[27765]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 10:29:07 volumiopc1 volumio[27765]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 10:29:07 volumiopc1 volumio[27765]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 10:29:07 volumiopc1 volumio[27765]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 10:29:07 volumiopc1 volumio[27765]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 10:29:07 volumiopc1 volumio[27765]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 10:29:07 volumiopc1 volumio[27765]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 10:29:07 volumiopc1 volumio[27765]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 10:29:07 volumiopc1 volumio[27765]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 10:29:07 volumiopc1 volumio[27765]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 10:29:07 volumiopc1 volumio[27765]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 10:29:07 volumiopc1 volumio[27765]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 10:29:07 volumiopc1 volumio[27765]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 10:29:07 volumiopc1 volumio[27765]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 10:29:07 volumiopc1 volumio[27765]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 10:29:07 volumiopc1 volumio[27765]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 10:29:07 volumiopc1 volumio[27765]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 10:29:08 volumiopc1 sudo[27963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:29:08 volumiopc1 sudo[27963]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:08 volumiopc1 sudo[27963]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:08 volumiopc1 sudo[27966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:29:08 volumiopc1 sudo[27966]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:08 volumiopc1 mpd[27861]: Nov 26 10:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 10:29:08 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Nov 26 10:29:08 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:08] [connect] Successful connection Nov 26 10:29:08 volumiopc1 sudo[27966]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:08 volumiopc1 systemd[1]: Started Music Player Daemon. Nov 26 10:29:08 volumiopc1 sudo[27839]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:08 volumiopc1 sudo[27849]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:08 volumiopc1 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:08 volumiopc1 volumio[27765]: info: logitechmediaserver started Nov 26 10:29:08 volumiopc1 volumio[27765]: info: MPD Permissions set Nov 26 10:29:08 volumiopc1 volumio[27765]: info: MPD Permissions set Nov 26 10:29:09 volumiopc1 volumio[27765]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreStateMachine::pushState Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:09 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Nov 26 10:29:09 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732616948 101 Nov 26 10:29:09 volumiopc1 volumio[27765]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Nov 26 10:29:09 volumiopc1 volumio[27765]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreStateMachine::pushState Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:09 volumiopc1 sudo[27894]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:09 volumiopc1 volumio[27765]: info: [squeezelite_mc] Server discovery started Nov 26 10:29:09 volumiopc1 volumio[27765]: info: [squeezelite_mc] Player finder started Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:09 volumiopc1 volumio[27765]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:09 volumiopc1 volumio[27765]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 10:29:09 volumiopc1 volumio[27765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:29:09 volumiopc1 volumio[27765]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 10:29:09 volumiopc1 volumio[27765]: at doSend (dgram.js:714:16) Nov 26 10:29:09 volumiopc1 volumio[27765]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 26 10:29:09 volumiopc1 volumio[27765]: at afterDns (dgram.js:660:5) Nov 26 10:29:09 volumiopc1 volumio[27765]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 26 10:29:09 volumiopc1 volumio[27765]: errno: -101, Nov 26 10:29:09 volumiopc1 volumio[27765]: code: 'ENETUNREACH', Nov 26 10:29:09 volumiopc1 volumio[27765]: syscall: 'send', Nov 26 10:29:09 volumiopc1 volumio[27765]: address: '255.255.255.255', Nov 26 10:29:09 volumiopc1 volumio[27765]: port: 3483 Nov 26 10:29:09 volumiopc1 volumio[27765]: } Nov 26 10:29:09 volumiopc1 volumio[27765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:29:10 volumiopc1 volumio[27977]: cset: --> shielding system active with Nov 26 10:29:10 volumiopc1 volumio[27977]: cset: "system" cpuset of CPUSPEC(1) with 172 tasks running Nov 26 10:29:10 volumiopc1 volumio[27977]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 26 10:29:10 volumiopc1 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 26 10:29:11 volumiopc1 sudo[28005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:28 Nov 26 10:29:11 volumiopc1 sudo[28005]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:11 volumiopc1 sudo[28005]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:11 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 26 10:29:11 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 26 10:29:11 volumiopc1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 10:29:11 volumiopc1 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 10:29:11 volumiopc1 systemd[1]: Started dynamicswap service. Nov 26 10:29:11 volumiopc1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 10:29:11 volumiopc1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 127. Nov 26 10:29:11 volumiopc1 systemd[1]: Stopped Volumio Backend Module. Nov 26 10:29:11 volumiopc1 systemd[1]: Started Volumio Backend Module. Nov 26 10:29:11 volumiopc1 systemd[1]: dynamicswap.service: Succeeded. Nov 26 10:29:15 volumiopc1 volumio[28018]: info: ------------------------------------------- Nov 26 10:29:15 volumiopc1 volumio[28018]: info: ----- Volumio3 ---- Nov 26 10:29:15 volumiopc1 volumio[28018]: info: ------------------------------------------- Nov 26 10:29:15 volumiopc1 volumio[28018]: info: ----- System startup ---- Nov 26 10:29:15 volumiopc1 volumio[28018]: info: ------------------------------------------- Nov 26 10:29:16 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:16] [connect] Successful connection Nov 26 10:29:17 volumiopc1 volumio[28018]: info: MYVOLUMIO Environment detected Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Plugin folders cleanup Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning into folder /volumio/app/plugins/ Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category audio_interface Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category miscellanea Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category music_service Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category plugins.json Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category system_controller Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category user_interface Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning into folder /data/plugins/ Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category music_service Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category system_hardware Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Scanning category user_interface Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Plugin folders cleanup completed Nov 26 10:29:17 volumiopc1 volumio[28018]: info: ------------------------------------------- Nov 26 10:29:17 volumiopc1 volumio[28018]: info: ----- Core plugins startup ---- Nov 26 10:29:17 volumiopc1 volumio[28018]: info: ------------------------------------------- Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Adding plugin upnp to MyMusic Plugins Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Loading plugins from folder /data/plugins/ Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Loading plugin "system"... Nov 26 10:29:17 volumiopc1 volumio[28018]: info: Loading plugin "appearance"... Nov 26 10:29:18 volumiopc1 squeezeboxserver[27887]: [24-11-26 10:29:14.1695] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 - x86_64-linux-gnu-thread-multi Nov 26 10:29:18 volumiopc1 squeezeboxserver[27887]: [24-11-26 10:29:15.3445] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 10:29:18 volumiopc1 squeezeboxserver[27887]: [10:29:18.115155] main:1714 Starting squeeze2upnp version: v2.2.2 (May 12 2023 @ 06:49:24) Nov 26 10:29:18 volumiopc1 squeezeboxserver[27887]: [10:29:18.178609] main:1751 Cannot start, exiting Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "network"... Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Refreshing Cached IP Addresses Nov 26 10:29:20 volumiopc1 sudo[28037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:29:20 volumiopc1 sudo[28037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:20 volumiopc1 sudo[28037]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:20 volumiopc1 sudo[28039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "services"... Nov 26 10:29:20 volumiopc1 sudo[28039]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "alsa_controller"... Nov 26 10:29:20 volumiopc1 sudo[28042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 10:29:20 volumiopc1 sudo[28039]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:20 volumiopc1 sudo[28042]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:20 volumiopc1 sudo[28042]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:20 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "wizard"... Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "networkfs"... Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Starting Udev Watcher for removable devices Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Ignoring mount for partition: boot Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Ignoring mount for partition: volumio Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Ignoring mount for partition: volumio_data Nov 26 10:29:20 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:20 volumiopc1 volumio[28018]: info: Loading plugin "volumio_command_line_client"... Nov 26 10:29:21 volumiopc1 volumio[28018]: info: Loading plugin "upnp"... Nov 26 10:29:21 volumiopc1 volumio[28018]: info: [1732616961020] Starting Upmpd Daemon Nov 26 10:29:21 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:21 volumiopc1 volumio[28018]: info: Loading plugin "my_music"... Nov 26 10:29:21 volumiopc1 volumio[28018]: info: Loading plugin "mpd"... Nov 26 10:29:21 volumiopc1 volumio[28018]: info: Loading plugin "upnp_browser"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "alarm-clock"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "airplay_emulation"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Starting Shairport Sync Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "last_100"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "webradio"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "i2s_dacs"... Nov 26 10:29:22 volumiopc1 volumio[28018]: info: I2S DAC not set, start Auto-detection Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "volumiodiscovery"... Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** For more information see Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** For more information see Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:22 volumiopc1 volumio[28018]: *** WARNING *** For more information see Nov 26 10:29:22 volumiopc1 node[28018]: *** WARNING *** For more information see Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Discovery: Started advertising with name: VolumioPC1 Nov 26 10:29:22 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:22 volumiopc1 volumio[28018]: info: Loading plugin "squeezelite_mc"... Nov 26 10:29:23 volumiopc1 volumio[28018]: info: Loading plugin "now_playing"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "outputs"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "albumart"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Plugin example_plugin is not enabled Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "inputs"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "updater_comm"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Plugin mpdemulation is not enabled Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "rest_api"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "websocket"... Nov 26 10:29:25 volumiopc1 volumio[28018]: info: Loading plugin "lms"... Nov 26 10:29:25 volumiopc1 volumio[28018]: Forking 1 albumart workers Nov 26 10:29:26 volumiopc1 volumio[28018]: info: Loading plugin "music_services_shield"... Nov 26 10:29:26 volumiopc1 volumio[28018]: info: Applying required configuration parameters for plugin music_services_shield Nov 26 10:29:26 volumiopc1 volumio[28018]: info: Loading plugin "Systeminfo"... Nov 26 10:29:27 volumiopc1 volumio[28018]: Starting albumart workers Nov 26 10:29:27 volumiopc1 volumio[28018]: info: Loading i18n strings for locale de Nov 26 10:29:27 volumiopc1 volumio[28018]: Updating browse sources language Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::initPlayerControls Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:27 volumiopc1 volumio[28018]: Express server listening on port 3000 Nov 26 10:29:27 volumiopc1 volumio[28018]: [Metrics] WebUI: 13s 789.21ms Nov 26 10:29:27 volumiopc1 volumio[28018]: info: Setting Device type: x86 Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreStateMachine::resetVolumioState Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreStateMachine::getcurrentVolume Nov 26 10:29:27 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:28 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:28 volumiopc1 volumio[28018]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Completed loading Core Plugins Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Preparing to generate the ALSA configuration file Nov 26 10:29:28 volumiopc1 volumio[28018]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 10:29:28 volumiopc1 volumio[28018]: wlan0 Interface doesn't support scanning. Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Cannot use regular scanning, forcing with ap-force Nov 26 10:29:28 volumiopc1 sudo[28079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 10:29:28 volumiopc1 sudo[28079]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:28 volumiopc1 sudo[28079]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:28 volumiopc1 volumio[28018]: command failed: No such device (-19) Nov 26 10:29:28 volumiopc1 volumio[28018]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 26 10:29:28 volumiopc1 volumio[28018]: command failed: No such device (-19) Nov 26 10:29:28 volumiopc1 volumio[28018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1 Nov 26 10:29:28 volumiopc1 volumio[28018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 Nov 26 10:29:28 volumiopc1 volumio[28018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Reloading queue from file Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Asound.conf file unchanged, so no further update is needed Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Output device has changed, restarting MPD Nov 26 10:29:28 volumiopc1 sudo[28089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:28 volumiopc1 volumio[28018]: info: Output device has changed, restarting Shairport Sync Nov 26 10:29:28 volumiopc1 sudo[28089]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:28 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:28 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:28 volumiopc1 sudo[28089]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:28 volumiopc1 sudo[28091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:28 volumiopc1 sudo[28091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 systemd[1]: musicservicesshield.service: Succeeded. Nov 26 10:29:29 volumiopc1 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 26 10:29:29 volumiopc1 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:29 volumiopc1 systemd[1]: Stopping Music Player Daemon... Nov 26 10:29:29 volumiopc1 volumio[28018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:29 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:29] [connect] Successful connection Nov 26 10:29:29 volumiopc1 volumio[28018]: info: ___________ START PLUGINS ___________ Nov 26 10:29:29 volumiopc1 volumio[28018]: info: ControllerMpd::onStart: Initializing MPD Nov 26 10:29:29 volumiopc1 volumio[28018]: info: Creating MPD Configuration file Nov 26 10:29:29 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:29 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:29 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:29 volumiopc1 sudo[28099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:29 volumiopc1 sudo[28099]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 sudo[28099]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:29 volumiopc1 volumio[28018]: info: [1732616969199] CoreMusicLibrary::Adding element Medienserver Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:29 volumiopc1 sudo[28102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:29 volumiopc1 sudo[28098]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:29 volumiopc1 sudo[28102]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:29 volumiopc1 sudo[28098]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:29 volumiopc1 sudo[28098]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:29 volumiopc1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 26 10:29:29 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:29 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:29 volumiopc1 volumio[28018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:29 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:29 volumiopc1 volumio[28018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:29 volumiopc1 volumio[28018]: info: [1732616969494] CoreMusicLibrary::Adding element Last_100 Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:29 volumiopc1 volumio[28018]: info: [1732616969497] CoreMusicLibrary::Adding element Webradio Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:29:29 volumiopc1 sudo[28109]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:29 volumiopc1 sudo[28109]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 volumio[28018]: info: [squeezelite_mc] Starting proxy server... Nov 26 10:29:29 volumiopc1 sudo[28109]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:29 volumiopc1 volumio[28018]: info: [now-playing-config] Config is up to date. Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 10:29:29 volumiopc1 volumio[28018]: info: Discovery: Getting this device information Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioGetState Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:29 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 10:29:29 volumiopc1 sudo[28116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 10:29:29 volumiopc1 sudo[28116]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:29 volumiopc1 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 10:29:29 volumiopc1 squeezeboxserver[27887]: [24-11-26 10:29:18.8098] Slim::Networking::Async::Socket::UDP::mcast_add (48) Error: While adding multicast membership, UPnP may not work properly: No such device Nov 26 10:29:30 volumiopc1 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 10:29:30 volumiopc1 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 10:29:30 volumiopc1 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 10:29:30 volumiopc1 systemd[1]: Started Logitech Media Server Daemon. Nov 26 10:29:30 volumiopc1 sudo[28116]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:30 volumiopc1 volumio[28018]: info: Volumio Calling Home Nov 26 10:29:30 volumiopc1 sudo[28147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 26 10:29:30 volumiopc1 sudo[28147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:30 volumiopc1 volumio[28018]: info: [squeezelite_mc] Proxy server started on port 33281 Nov 26 10:29:31 volumiopc1 volumio[28018]: info: [now-playing-app] App is listening on port 4004. Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::setRepeat null single undefined Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::pushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::setRandom null Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::pushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::pushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreStateMachine::updateTrackBlock Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrackBlock Nov 26 10:29:31 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:32 volumiopc1 volumio[28018]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 10:29:32 volumiopc1 volumio[28018]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 10:29:32 volumiopc1 volumio[28018]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 10:29:32 volumiopc1 volumio[28018]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 10:29:32 volumiopc1 volumio[28018]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 10:29:32 volumiopc1 volumio[28018]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 10:29:32 volumiopc1 volumio[28018]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 10:29:32 volumiopc1 volumio[28018]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 10:29:32 volumiopc1 volumio[28018]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 10:29:32 volumiopc1 volumio[28018]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 10:29:32 volumiopc1 volumio[28018]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 10:29:32 volumiopc1 volumio[28018]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 10:29:32 volumiopc1 volumio[28018]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 10:29:32 volumiopc1 volumio[28018]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 10:29:32 volumiopc1 volumio[28018]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 10:29:32 volumiopc1 volumio[28018]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 10:29:32 volumiopc1 volumio[28018]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 10:29:32 volumiopc1 sudo[28166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:29:32 volumiopc1 sudo[28166]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:32 volumiopc1 sudo[28166]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:32 volumiopc1 sudo[28169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:29:32 volumiopc1 sudo[28169]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:32 volumiopc1 sudo[28169]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:32 volumiopc1 mpd[28114]: Nov 26 10:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 10:29:33 volumiopc1 volumio[28018]: info: logitechmediaserver started Nov 26 10:29:33 volumiopc1 systemd[1]: Started Music Player Daemon. Nov 26 10:29:33 volumiopc1 sudo[28102]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:33 volumiopc1 sudo[28091]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:33 volumiopc1 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:33 volumiopc1 volumio[28018]: info: MPD Permissions set Nov 26 10:29:33 volumiopc1 volumio[28018]: info: MPD Permissions set Nov 26 10:29:33 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732616969 101 Nov 26 10:29:33 volumiopc1 volumio[28018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Nov 26 10:29:33 volumiopc1 volumio[28018]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CoreStateMachine::pushState Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:33 volumiopc1 sudo[28147]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:33 volumiopc1 volumio[28018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Nov 26 10:29:33 volumiopc1 volumio[28018]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CoreStateMachine::pushState Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:33 volumiopc1 volumio[28018]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:34 volumiopc1 volumio[28018]: info: [squeezelite_mc] Server discovery started Nov 26 10:29:34 volumiopc1 volumio[28018]: info: [squeezelite_mc] Player finder started Nov 26 10:29:34 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:34 volumiopc1 volumio[28018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:34 volumiopc1 volumio[28018]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 10:29:34 volumiopc1 sudo[28196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Nov 26 10:29:34 volumiopc1 sudo[28196]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:34 volumiopc1 volumio[28018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:29:34 volumiopc1 volumio[28018]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 10:29:34 volumiopc1 volumio[28018]: at doSend (dgram.js:714:16) Nov 26 10:29:34 volumiopc1 volumio[28018]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 26 10:29:34 volumiopc1 volumio[28018]: at afterDns (dgram.js:660:5) Nov 26 10:29:34 volumiopc1 volumio[28018]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 26 10:29:34 volumiopc1 volumio[28018]: errno: -101, Nov 26 10:29:34 volumiopc1 volumio[28018]: code: 'ENETUNREACH', Nov 26 10:29:34 volumiopc1 volumio[28018]: syscall: 'send', Nov 26 10:29:34 volumiopc1 volumio[28018]: address: '255.255.255.255', Nov 26 10:29:34 volumiopc1 volumio[28018]: port: 3483 Nov 26 10:29:34 volumiopc1 volumio[28018]: } Nov 26 10:29:34 volumiopc1 volumio[28018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:29:34 volumiopc1 volumio[28180]: cset: --> shielding system active with Nov 26 10:29:34 volumiopc1 volumio[28180]: cset: "system" cpuset of CPUSPEC(1) with 170 tasks running Nov 26 10:29:34 volumiopc1 volumio[28180]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 26 10:29:34 volumiopc1 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 26 10:29:34 volumiopc1 systemd[1]: Reloading. Nov 26 10:29:35 volumiopc1 sudo[28228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:28 Nov 26 10:29:35 volumiopc1 sudo[28228]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:36 volumiopc1 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 26 10:29:36 volumiopc1 sudo[28228]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:36 volumiopc1 systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Nov 26 10:29:36 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 26 10:29:36 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 26 10:29:36 volumiopc1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 10:29:36 volumiopc1 sudo[28196]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:36 volumiopc1 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 10:29:37 volumiopc1 systemd[1]: Started dynamicswap service. Nov 26 10:29:37 volumiopc1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 10:29:37 volumiopc1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 128. Nov 26 10:29:37 volumiopc1 systemd[1]: dynamicswap.service: Succeeded. Nov 26 10:29:37 volumiopc1 systemd[1]: Stopped Volumio Backend Module. Nov 26 10:29:37 volumiopc1 systemd[1]: Started Volumio Backend Module. Nov 26 10:29:41 volumiopc1 volumio[28241]: info: ------------------------------------------- Nov 26 10:29:41 volumiopc1 volumio[28241]: info: ----- Volumio3 ---- Nov 26 10:29:41 volumiopc1 volumio[28241]: info: ------------------------------------------- Nov 26 10:29:41 volumiopc1 volumio[28241]: info: ----- System startup ---- Nov 26 10:29:41 volumiopc1 volumio[28241]: info: ------------------------------------------- Nov 26 10:29:41 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:41] [connect] Successful connection Nov 26 10:29:42 volumiopc1 squeezeboxserver[28140]: [24-11-26 10:29:38.7334] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 - x86_64-linux-gnu-thread-multi Nov 26 10:29:42 volumiopc1 squeezeboxserver[28140]: [24-11-26 10:29:39.9166] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 10:29:42 volumiopc1 squeezeboxserver[28140]: [10:29:42.689552] main:1714 Starting squeeze2upnp version: v2.2.2 (May 12 2023 @ 06:49:24) Nov 26 10:29:42 volumiopc1 volumio[28241]: info: MYVOLUMIO Environment detected Nov 26 10:29:42 volumiopc1 squeezeboxserver[28140]: [10:29:42.763980] main:1751 Cannot start, exiting Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Plugin folders cleanup Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning into folder /volumio/app/plugins/ Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category audio_interface Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category miscellanea Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category music_service Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category plugins.json Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category system_controller Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category user_interface Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning into folder /data/plugins/ Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category music_service Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category system_hardware Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Scanning category user_interface Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Plugin folders cleanup completed Nov 26 10:29:42 volumiopc1 volumio[28241]: info: ------------------------------------------- Nov 26 10:29:42 volumiopc1 volumio[28241]: info: ----- Core plugins startup ---- Nov 26 10:29:42 volumiopc1 volumio[28241]: info: ------------------------------------------- Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Adding plugin upnp to MyMusic Plugins Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Loading plugins from folder /data/plugins/ Nov 26 10:29:42 volumiopc1 volumio[28241]: info: Loading plugin "system"... Nov 26 10:29:43 volumiopc1 volumio[28241]: info: Loading plugin "appearance"... Nov 26 10:29:45 volumiopc1 volumio[28241]: info: Loading plugin "network"... Nov 26 10:29:45 volumiopc1 volumio[28241]: info: Refreshing Cached IP Addresses Nov 26 10:29:45 volumiopc1 sudo[28260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:29:45 volumiopc1 sudo[28260]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:45 volumiopc1 sudo[28260]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:45 volumiopc1 volumio[28241]: info: Loading plugin "services"... Nov 26 10:29:45 volumiopc1 sudo[28262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:29:45 volumiopc1 volumio[28241]: info: Loading plugin "alsa_controller"... Nov 26 10:29:45 volumiopc1 sudo[28262]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:46 volumiopc1 sudo[28265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 10:29:46 volumiopc1 sudo[28265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:46 volumiopc1 sudo[28262]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:46 volumiopc1 sudo[28265]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:46 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "wizard"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "networkfs"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Starting Udev Watcher for removable devices Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Ignoring mount for partition: boot Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Ignoring mount for partition: volumio Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Ignoring mount for partition: volumio_data Nov 26 10:29:46 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "volumio_command_line_client"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "upnp"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: [1732616986337] Starting Upmpd Daemon Nov 26 10:29:46 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "my_music"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "mpd"... Nov 26 10:29:46 volumiopc1 volumio[28241]: info: Loading plugin "upnp_browser"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "alarm-clock"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "airplay_emulation"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Starting Shairport Sync Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "last_100"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "webradio"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "i2s_dacs"... Nov 26 10:29:47 volumiopc1 volumio[28241]: info: I2S DAC not set, start Auto-detection Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "volumiodiscovery"... Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** For more information see Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:47 volumiopc1 volumio[28241]: *** WARNING *** For more information see Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** For more information see Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:29:47 volumiopc1 node[28241]: *** WARNING *** For more information see Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Discovery: Started advertising with name: VolumioPC1 Nov 26 10:29:47 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:29:47 volumiopc1 volumio[28241]: info: Loading plugin "squeezelite_mc"... Nov 26 10:29:48 volumiopc1 volumio[28241]: info: Loading plugin "now_playing"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "outputs"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "albumart"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Plugin example_plugin is not enabled Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "inputs"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "updater_comm"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Plugin mpdemulation is not enabled Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "rest_api"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "websocket"... Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "lms"... Nov 26 10:29:50 volumiopc1 volumio[28241]: Forking 1 albumart workers Nov 26 10:29:50 volumiopc1 volumio[28241]: info: Loading plugin "music_services_shield"... Nov 26 10:29:51 volumiopc1 volumio[28241]: info: Applying required configuration parameters for plugin music_services_shield Nov 26 10:29:51 volumiopc1 volumio[28241]: info: Loading plugin "Systeminfo"... Nov 26 10:29:51 volumiopc1 volumio[28241]: Starting albumart workers Nov 26 10:29:52 volumiopc1 volumio[28241]: info: Loading i18n strings for locale de Nov 26 10:29:52 volumiopc1 volumio[28241]: Updating browse sources language Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::initPlayerControls Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:52 volumiopc1 volumio[28241]: Express server listening on port 3000 Nov 26 10:29:52 volumiopc1 volumio[28241]: [Metrics] WebUI: 13s 292.20ms Nov 26 10:29:52 volumiopc1 volumio[28241]: info: Setting Device type: x86 Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreStateMachine::resetVolumioState Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreStateMachine::getcurrentVolume Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:52 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:53 volumiopc1 volumio[28241]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Completed loading Core Plugins Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Preparing to generate the ALSA configuration file Nov 26 10:29:53 volumiopc1 volumio[28241]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 10:29:53 volumiopc1 volumio[28241]: wlan0 Interface doesn't support scanning. Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Cannot use regular scanning, forcing with ap-force Nov 26 10:29:53 volumiopc1 sudo[28302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 10:29:53 volumiopc1 sudo[28302]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:53 volumiopc1 sudo[28302]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:53 volumiopc1 volumio[28241]: command failed: No such device (-19) Nov 26 10:29:53 volumiopc1 volumio[28241]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 26 10:29:53 volumiopc1 volumio[28241]: command failed: No such device (-19) Nov 26 10:29:53 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1 Nov 26 10:29:53 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 Nov 26 10:29:53 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Reloading queue from file Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Asound.conf file unchanged, so no further update is needed Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Output device has changed, restarting MPD Nov 26 10:29:53 volumiopc1 sudo[28313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:53 volumiopc1 sudo[28313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Output device has changed, restarting Shairport Sync Nov 26 10:29:53 volumiopc1 sudo[28313]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:53 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:53 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:53 volumiopc1 sudo[28316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:53 volumiopc1 sudo[28316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:53 volumiopc1 systemd[1]: musicservicesshield.service: Succeeded. Nov 26 10:29:53 volumiopc1 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 26 10:29:53 volumiopc1 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:53 volumiopc1 systemd[1]: Stopping Music Player Daemon... Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:53 volumiopc1 volumio[28241]: info: ___________ START PLUGINS ___________ Nov 26 10:29:53 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:53 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:53 volumiopc1 volumio[28241]: info: ControllerMpd::onStart: Initializing MPD Nov 26 10:29:53 volumiopc1 volumio[28241]: info: Creating MPD Configuration file Nov 26 10:29:53 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:54 volumiopc1 sudo[28323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:29:54 volumiopc1 sudo[28323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:54 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:54] [connect] Successful connection Nov 26 10:29:54 volumiopc1 sudo[28323]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:54 volumiopc1 volumio[28241]: info: [1732616994072] CoreMusicLibrary::Adding element Medienserver Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:54 volumiopc1 sudo[28321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:54 volumiopc1 sudo[28321]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:54 volumiopc1 sudo[28321]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:54 volumiopc1 sudo[28326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:29:54 volumiopc1 sudo[28326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:54 volumiopc1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 26 10:29:54 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:29:54 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:29:54 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:29:54 volumiopc1 volumio[28241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:29:54 volumiopc1 volumio[28241]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:54 volumiopc1 volumio[28241]: info: [1732616994457] CoreMusicLibrary::Adding element Last_100 Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:29:54 volumiopc1 volumio[28241]: info: [1732616994471] CoreMusicLibrary::Adding element Webradio Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:29:54 volumiopc1 sudo[28333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:29:54 volumiopc1 sudo[28333]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:54 volumiopc1 sudo[28333]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:54 volumiopc1 volumio[28241]: info: [squeezelite_mc] Starting proxy server... Nov 26 10:29:54 volumiopc1 volumio[28241]: info: [now-playing-config] Config is up to date. Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 10:29:54 volumiopc1 volumio[28241]: info: Discovery: Getting this device information Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioGetState Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:54 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 10:29:54 volumiopc1 sudo[28340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 10:29:54 volumiopc1 sudo[28340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:54 volumiopc1 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 10:29:54 volumiopc1 squeezeboxserver[28140]: [24-11-26 10:29:43.4018] Slim::Networking::Async::Socket::UDP::mcast_add (48) Error: While adding multicast membership, UPnP may not work properly: No such device Nov 26 10:29:55 volumiopc1 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 10:29:55 volumiopc1 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 10:29:55 volumiopc1 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 10:29:55 volumiopc1 systemd[1]: Started Logitech Media Server Daemon. Nov 26 10:29:55 volumiopc1 sudo[28340]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:55 volumiopc1 volumio[28241]: info: Volumio Calling Home Nov 26 10:29:55 volumiopc1 sudo[28371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 26 10:29:55 volumiopc1 sudo[28371]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:56 volumiopc1 volumio[28241]: info: [squeezelite_mc] Proxy server started on port 33059 Nov 26 10:29:56 volumiopc1 volumio[28241]: info: [now-playing-app] App is listening on port 4004. Nov 26 10:29:56 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::setRepeat null single undefined Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::pushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::setRandom null Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::pushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::pushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreStateMachine::updateTrackBlock Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrackBlock Nov 26 10:29:57 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:29:57 volumiopc1 volumio[28241]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 10:29:57 volumiopc1 volumio[28241]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 10:29:57 volumiopc1 volumio[28241]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 10:29:57 volumiopc1 volumio[28241]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 10:29:57 volumiopc1 volumio[28241]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 10:29:57 volumiopc1 volumio[28241]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 10:29:57 volumiopc1 volumio[28241]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 10:29:57 volumiopc1 volumio[28241]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 10:29:57 volumiopc1 volumio[28241]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 10:29:57 volumiopc1 volumio[28241]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 10:29:57 volumiopc1 volumio[28241]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 10:29:57 volumiopc1 volumio[28241]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 10:29:57 volumiopc1 volumio[28241]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 10:29:57 volumiopc1 volumio[28241]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 10:29:57 volumiopc1 volumio[28241]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 10:29:57 volumiopc1 volumio[28241]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 10:29:57 volumiopc1 volumio[28241]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 10:29:58 volumiopc1 sudo[28390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:29:58 volumiopc1 sudo[28390]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:58 volumiopc1 sudo[28390]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:58 volumiopc1 sudo[28393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:29:58 volumiopc1 sudo[28393]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:29:58 volumiopc1 mpd[28338]: Nov 26 10:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 10:29:58 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Nov 26 10:29:58 volumiopc1 sudo[28393]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:58 volumiopc1 volumio[28241]: info: logitechmediaserver started Nov 26 10:29:58 volumiopc1 volumio[28241]: info: MPD Permissions set Nov 26 10:29:58 volumiopc1 volumio[28241]: info: MPD Permissions set Nov 26 10:29:58 volumiopc1 systemd[1]: Started Music Player Daemon. Nov 26 10:29:58 volumiopc1 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 26 10:29:58 volumiopc1 sudo[28316]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:58 volumiopc1 sudo[28326]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:59 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:29:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732616994 101 Nov 26 10:29:59 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Nov 26 10:29:59 volumiopc1 volumio[28241]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreStateMachine::pushState Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:59 volumiopc1 volumio[28241]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Nov 26 10:29:59 volumiopc1 volumio[28241]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreStateMachine::pushState Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CorePlayQueue::getTrack 0 Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreCommandRouter::volumioPushState Nov 26 10:29:59 volumiopc1 volumio[28241]: info: [squeezelite_mc] Server discovery started Nov 26 10:29:59 volumiopc1 volumio[28241]: info: [squeezelite_mc] Player finder started Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:29:59 volumiopc1 volumio[28241]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:29:59 volumiopc1 volumio[28241]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 10:29:59 volumiopc1 volumio[28241]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:29:59 volumiopc1 sudo[28371]: pam_unix(sudo:session): session closed for user root Nov 26 10:29:59 volumiopc1 volumio[28241]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 10:29:59 volumiopc1 volumio[28241]: at doSend (dgram.js:714:16) Nov 26 10:29:59 volumiopc1 volumio[28241]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 26 10:29:59 volumiopc1 volumio[28241]: at afterDns (dgram.js:660:5) Nov 26 10:29:59 volumiopc1 volumio[28241]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 26 10:29:59 volumiopc1 volumio[28241]: errno: -101, Nov 26 10:29:59 volumiopc1 volumio[28241]: code: 'ENETUNREACH', Nov 26 10:29:59 volumiopc1 volumio[28241]: syscall: 'send', Nov 26 10:29:59 volumiopc1 volumio[28241]: address: '255.255.255.255', Nov 26 10:29:59 volumiopc1 volumio[28241]: port: 3483 Nov 26 10:29:59 volumiopc1 volumio[28241]: } Nov 26 10:29:59 volumiopc1 volumio[28241]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:30:00 volumiopc1 volumio[28404]: cset: --> shielding system active with Nov 26 10:30:00 volumiopc1 volumio[28404]: cset: "system" cpuset of CPUSPEC(1) with 173 tasks running Nov 26 10:30:00 volumiopc1 volumio[28404]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 26 10:30:00 volumiopc1 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 26 10:30:00 volumiopc1 sudo[28430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:29 Nov 26 10:30:00 volumiopc1 sudo[28430]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:01 volumiopc1 sudo[28430]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:01 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:30:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 26 10:30:01 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:30:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 26 10:30:01 volumiopc1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 26 10:30:01 volumiopc1 systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 26 10:30:01 volumiopc1 systemd[1]: Started dynamicswap service. Nov 26 10:30:01 volumiopc1 systemd[1]: dynamicswap.service: Succeeded. Nov 26 10:30:01 volumiopc1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 26 10:30:01 volumiopc1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 129. Nov 26 10:30:01 volumiopc1 systemd[1]: Started dynamicswap service. Nov 26 10:30:01 volumiopc1 systemd[1]: Stopped Volumio Backend Module. Nov 26 10:30:01 volumiopc1 systemd[1]: Started Volumio Backend Module. Nov 26 10:30:01 volumiopc1 systemd[1]: dynamicswap.service: Succeeded. Nov 26 10:30:05 volumiopc1 volumio[28444]: info: ------------------------------------------- Nov 26 10:30:05 volumiopc1 volumio[28444]: info: ----- Volumio3 ---- Nov 26 10:30:05 volumiopc1 volumio[28444]: info: ------------------------------------------- Nov 26 10:30:05 volumiopc1 volumio[28444]: info: ----- System startup ---- Nov 26 10:30:05 volumiopc1 volumio[28444]: info: ------------------------------------------- Nov 26 10:30:06 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:30:06] [connect] Successful connection Nov 26 10:30:07 volumiopc1 volumio[28444]: info: MYVOLUMIO Environment detected Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Plugin folders cleanup Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning into folder /volumio/app/plugins/ Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category audio_interface Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category miscellanea Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category music_service Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category plugins.json Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category system_controller Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category user_interface Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning into folder /data/plugins/ Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category music_service Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category system_hardware Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Scanning category user_interface Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Plugin folders cleanup completed Nov 26 10:30:07 volumiopc1 volumio[28444]: info: ------------------------------------------- Nov 26 10:30:07 volumiopc1 volumio[28444]: info: ----- Core plugins startup ---- Nov 26 10:30:07 volumiopc1 volumio[28444]: info: ------------------------------------------- Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Loading plugins from folder /volumio/app/plugins/ Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Adding plugin upnp to MyMusic Plugins Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Loading plugins from folder /data/plugins/ Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Loading plugin "system"... Nov 26 10:30:07 volumiopc1 volumio[28444]: info: Loading plugin "appearance"... Nov 26 10:30:08 volumiopc1 squeezeboxserver[28364]: [24-11-26 10:30:04.2526] main::init (377) Starting Logitech Media Server (v8.3.1, 1676361197, Fri 17 Feb 2023 06:37:09 AM CET) perl 5.028001 - x86_64-linux-gnu-thread-multi Nov 26 10:30:08 volumiopc1 squeezeboxserver[28364]: [24-11-26 10:30:05.4350] Slim::Utils::IPDetect::_init (138) Warning: Couldn't call connect() - falling back to 127.0.0.1 Nov 26 10:30:08 volumiopc1 squeezeboxserver[28364]: [10:30:08.501775] main:1714 Starting squeeze2upnp version: v2.2.2 (May 12 2023 @ 06:49:24) Nov 26 10:30:08 volumiopc1 squeezeboxserver[28364]: [10:30:08.560763] main:1751 Cannot start, exiting Nov 26 10:30:10 volumiopc1 volumio[28444]: info: Loading plugin "network"... Nov 26 10:30:10 volumiopc1 volumio[28444]: info: Refreshing Cached IP Addresses Nov 26 10:30:10 volumiopc1 sudo[28518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:30:10 volumiopc1 sudo[28518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:10 volumiopc1 sudo[28518]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:10 volumiopc1 volumio[28444]: info: Loading plugin "services"... Nov 26 10:30:10 volumiopc1 sudo[28521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:30:10 volumiopc1 sudo[28521]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:10 volumiopc1 volumio[28444]: info: Loading plugin "alsa_controller"... Nov 26 10:30:10 volumiopc1 sudo[28523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 10:30:10 volumiopc1 sudo[28523]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:10 volumiopc1 sudo[28521]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:10 volumiopc1 sudo[28523]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:11 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "wizard"... Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "networkfs"... Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Starting Udev Watcher for removable devices Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Ignoring mount for partition: boot Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Ignoring mount for partition: volumio Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Ignoring mount for partition: volumio_data Nov 26 10:30:11 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "volumio_command_line_client"... Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "upnp"... Nov 26 10:30:11 volumiopc1 volumio[28444]: info: [1732617011242] Starting Upmpd Daemon Nov 26 10:30:11 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "my_music"... Nov 26 10:30:11 volumiopc1 volumio[28444]: info: Loading plugin "mpd"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "upnp_browser"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "alarm-clock"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "airplay_emulation"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Starting Shairport Sync Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "last_100"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "webradio"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "i2s_dacs"... Nov 26 10:30:12 volumiopc1 volumio[28444]: info: I2S DAC not set, start Auto-detection Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "volumiodiscovery"... Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** For more information see Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:30:12 volumiopc1 volumio[28444]: *** WARNING *** For more information see Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** For more information see Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 26 10:30:12 volumiopc1 node[28444]: *** WARNING *** For more information see Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Discovery: Started advertising with name: VolumioPC1 Nov 26 10:30:12 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 26 10:30:12 volumiopc1 volumio[28444]: info: Loading plugin "squeezelite_mc"... Nov 26 10:30:13 volumiopc1 volumio[28444]: info: Loading plugin "now_playing"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "outputs"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "albumart"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Plugin example_plugin is not enabled Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "inputs"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "updater_comm"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Plugin mpdemulation is not enabled Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "rest_api"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "websocket"... Nov 26 10:30:15 volumiopc1 volumio[28444]: info: Loading plugin "lms"... Nov 26 10:30:15 volumiopc1 volumio[28444]: Forking 1 albumart workers Nov 26 10:30:16 volumiopc1 volumio[28444]: info: Loading plugin "music_services_shield"... Nov 26 10:30:16 volumiopc1 volumio[28444]: info: Applying required configuration parameters for plugin music_services_shield Nov 26 10:30:16 volumiopc1 volumio[28444]: info: Loading plugin "Systeminfo"... Nov 26 10:30:17 volumiopc1 volumio[28444]: Starting albumart workers Nov 26 10:30:17 volumiopc1 volumio[28444]: info: Loading i18n strings for locale de Nov 26 10:30:17 volumiopc1 volumio[28444]: Updating browse sources language Nov 26 10:30:17 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::initPlayerControls Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: Express server listening on port 3000 Nov 26 10:30:18 volumiopc1 volumio[28444]: [Metrics] WebUI: 14s 75.44ms Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Setting Device type: x86 Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreStateMachine::resetVolumioState Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreStateMachine::getcurrentVolume Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:30:18 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:30:18 volumiopc1 volumio[28444]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Completed loading Core Plugins Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Preparing to generate the ALSA configuration file Nov 26 10:30:18 volumiopc1 volumio[28444]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 10:30:18 volumiopc1 volumio[28444]: wlan0 Interface doesn't support scanning. Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Cannot use regular scanning, forcing with ap-force Nov 26 10:30:18 volumiopc1 sudo[28560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 10:30:18 volumiopc1 sudo[28560]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:18 volumiopc1 sudo[28560]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:18 volumiopc1 volumio[28444]: command failed: No such device (-19) Nov 26 10:30:18 volumiopc1 volumio[28444]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 26 10:30:18 volumiopc1 volumio[28444]: command failed: No such device (-19) Nov 26 10:30:18 volumiopc1 volumio[28444]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1 Nov 26 10:30:18 volumiopc1 volumio[28444]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2 Nov 26 10:30:18 volumiopc1 volumio[28444]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Nov 26 10:30:18 volumiopc1 volumio-remote-updater[381]: [2024-11-26 10:30:18] [connect] Successful connection Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Reloading queue from file Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Asound.conf file unchanged, so no further update is needed Nov 26 10:30:18 volumiopc1 volumio[28444]: info: Output device has changed, restarting MPD Nov 26 10:30:19 volumiopc1 sudo[28570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:30:19 volumiopc1 sudo[28570]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 sudo[28570]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Output device has changed, restarting Shairport Sync Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:30:19 volumiopc1 sudo[28573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:30:19 volumiopc1 sudo[28573]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 systemd[1]: musicservicesshield.service: Succeeded. Nov 26 10:30:19 volumiopc1 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Nov 26 10:30:19 volumiopc1 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Nov 26 10:30:19 volumiopc1 systemd[1]: Stopping Music Player Daemon... Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:30:19 volumiopc1 volumio[28444]: info: ___________ START PLUGINS ___________ Nov 26 10:30:19 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:30:19 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:30:19 volumiopc1 volumio[28444]: info: ControllerMpd::onStart: Initializing MPD Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Creating MPD Configuration file Nov 26 10:30:19 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:30:19 volumiopc1 sudo[28580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 10:30:19 volumiopc1 sudo[28580]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 sudo[28580]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:30:19 volumiopc1 volumio[28444]: info: [1732617019416] CoreMusicLibrary::Adding element Medienserver Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:30:19 volumiopc1 sudo[28583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 10:30:19 volumiopc1 sudo[28578]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:30:19 volumiopc1 sudo[28578]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 sudo[28583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 sudo[28578]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:30:19 volumiopc1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 26 10:30:19 volumiopc1 systemd[1]: mpd.service: Succeeded. Nov 26 10:30:19 volumiopc1 systemd[1]: Stopped Music Player Daemon. Nov 26 10:30:19 volumiopc1 systemd[1]: Starting Music Player Daemon... Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 10:30:19 volumiopc1 sudo[28588]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 10:30:19 volumiopc1 sudo[28588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:30:19 volumiopc1 volumio[28444]: info: [1732617019810] CoreMusicLibrary::Adding element Last_100 Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 26 10:30:19 volumiopc1 volumio[28444]: info: [1732617019822] CoreMusicLibrary::Adding element Webradio Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 10:30:19 volumiopc1 sudo[28588]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:19 volumiopc1 volumio[28444]: info: [squeezelite_mc] Starting proxy server... Nov 26 10:30:19 volumiopc1 volumio[28444]: info: [now-playing-config] Config is up to date. Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 26 10:30:19 volumiopc1 volumio[28444]: info: Discovery: Getting this device information Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioGetState Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:19 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 26 10:30:20 volumiopc1 sudo[28597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Nov 26 10:30:20 volumiopc1 sudo[28597]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:20 volumiopc1 systemd[1]: Stopping Logitech Media Server Daemon... Nov 26 10:30:20 volumiopc1 squeezeboxserver[28364]: [24-11-26 10:30:09.2046] Slim::Networking::Async::Socket::UDP::mcast_add (48) Error: While adding multicast membership, UPnP may not work properly: No such device Nov 26 10:30:20 volumiopc1 systemd[1]: logitechmediaserver.service: Succeeded. Nov 26 10:30:20 volumiopc1 systemd[1]: Stopped Logitech Media Server Daemon. Nov 26 10:30:20 volumiopc1 systemd[1]: Starting Logitech Media Server Daemon... Nov 26 10:30:20 volumiopc1 systemd[1]: Started Logitech Media Server Daemon. Nov 26 10:30:20 volumiopc1 sudo[28597]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:20 volumiopc1 volumio[28444]: info: Volumio Calling Home Nov 26 10:30:21 volumiopc1 kernel: r8169 0000:01:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Nov 26 10:30:21 volumiopc1 dhcpcd[425]: eth0: carrier acquired Nov 26 10:30:21 volumiopc1 dhcpcd[425]: eth0: IAID f3:19:01:ef Nov 26 10:30:21 volumiopc1 dhcpcd[425]: eth0: probing address 192.168.0.210/24 Nov 26 10:30:21 volumiopc1 sudo[28628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Nov 26 10:30:21 volumiopc1 sudo[28628]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:21 volumiopc1 ifplugd(eth0)[681]: Link beat detected. Nov 26 10:30:21 volumiopc1 ifplugd(eth0)[681]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 26 10:30:21 volumiopc1 ifplugd(eth0)[681]: client: command failed: No such device (-19) Nov 26 10:30:21 volumiopc1 dhcpcd[425]: eth0: soliciting an IPv6 router Nov 26 10:30:21 volumiopc1 volumio[28444]: info: [squeezelite_mc] Proxy server started on port 33701 Nov 26 10:30:21 volumiopc1 volumio[28444]: info: [now-playing-app] App is listening on port 4004. Nov 26 10:30:22 volumiopc1 ifplugd(eth0)[681]: Program executed successfully. Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::setRepeat null single undefined Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::pushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioPushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::setRandom null Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::pushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioPushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::pushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioPushState Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreStateMachine::updateTrackBlock Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrackBlock Nov 26 10:30:22 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 10:30:23 volumiopc1 volumio[28444]: Unhandled rejection Error: No sockets available, cannot start. Nov 26 10:30:23 volumiopc1 volumio[28444]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 26 10:30:23 volumiopc1 volumio[28444]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 26 10:30:23 volumiopc1 volumio[28444]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 26 10:30:23 volumiopc1 volumio[28444]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 26 10:30:23 volumiopc1 volumio[28444]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 26 10:30:23 volumiopc1 volumio[28444]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 26 10:30:23 volumiopc1 volumio[28444]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 26 10:30:23 volumiopc1 volumio[28444]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 26 10:30:23 volumiopc1 volumio[28444]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 26 10:30:23 volumiopc1 volumio[28444]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 26 10:30:23 volumiopc1 volumio[28444]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 26 10:30:23 volumiopc1 volumio[28444]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 26 10:30:23 volumiopc1 volumio[28444]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 26 10:30:23 volumiopc1 volumio[28444]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 26 10:30:23 volumiopc1 volumio[28444]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 26 10:30:23 volumiopc1 volumio[28444]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 26 10:30:23 volumiopc1 sudo[28695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 10:30:23 volumiopc1 sudo[28695]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:23 volumiopc1 sudo[28695]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:23 volumiopc1 mpd[28595]: Nov 26 10:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 10:30:23 volumiopc1 sudo[28698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 10:30:23 volumiopc1 sudo[28698]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:23 volumiopc1 volumio[28444]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Nov 26 10:30:23 volumiopc1 sudo[28698]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:24 volumiopc1 systemd[1]: Started Music Player Daemon. Nov 26 10:30:24 volumiopc1 sudo[28573]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:24 volumiopc1 sudo[28583]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:24 volumiopc1 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Nov 26 10:30:24 volumiopc1 volumio[28444]: info: logitechmediaserver started Nov 26 10:30:24 volumiopc1 volumio[28444]: info: MPD Permissions set Nov 26 10:30:24 volumiopc1 volumio[28444]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:30:24 volumiopc1 volumio[28444]: info: CoreStateMachine::pushState Nov 26 10:30:24 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:24 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioPushState Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 26 10:30:25 volumiopc1 sudo[28628]: pam_unix(sudo:session): session closed for user root Nov 26 10:30:25 volumiopc1 volumio[28444]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Nov 26 10:30:25 volumiopc1 volumio[28444]: info: VolumeController:: Volume=100 Mute =false Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CoreStateMachine::pushState Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CorePlayQueue::getTrack 0 Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CoreCommandRouter::volumioPushState Nov 26 10:30:25 volumiopc1 volumio[28444]: info: [squeezelite_mc] Server discovery started Nov 26 10:30:25 volumiopc1 volumio[28444]: info: [squeezelite_mc] Player finder started Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 10:30:25 volumiopc1 volumio[28444]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 10:30:25 volumiopc1 volumio[28444]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Nov 26 10:30:25 volumiopc1 volumio[28444]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:30:25 volumiopc1 volumio[28444]: Error: send ENETUNREACH 255.255.255.255:3483 Nov 26 10:30:25 volumiopc1 volumio[28444]: at doSend (dgram.js:714:16) Nov 26 10:30:25 volumiopc1 volumio[28444]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:452:18) Nov 26 10:30:25 volumiopc1 volumio[28444]: at afterDns (dgram.js:660:5) Nov 26 10:30:25 volumiopc1 volumio[28444]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Nov 26 10:30:25 volumiopc1 volumio[28444]: errno: -101, Nov 26 10:30:25 volumiopc1 volumio[28444]: code: 'ENETUNREACH', Nov 26 10:30:25 volumiopc1 volumio[28444]: syscall: 'send', Nov 26 10:30:25 volumiopc1 volumio[28444]: address: '255.255.255.255', Nov 26 10:30:25 volumiopc1 volumio[28444]: port: 3483 Nov 26 10:30:25 volumiopc1 volumio[28444]: } Nov 26 10:30:25 volumiopc1 volumio[28444]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 10:30:25 volumiopc1 volumio[28708]: cset: --> shielding system active with Nov 26 10:30:25 volumiopc1 volumio[28708]: cset: "system" cpuset of CPUSPEC(1) with 171 tasks running Nov 26 10:30:25 volumiopc1 volumio[28708]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Nov 26 10:30:26 volumiopc1 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Nov 26 10:30:26 volumiopc1 sudo[28737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 10:29 Nov 26 10:30:26 volumiopc1 sudo[28737]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 10:30:27 volumiopc1 dhcpcd[425]: eth0: using static address 192.168.0.210/24 Nov 26 10:30:27 volumiopc1 avahi-daemon[364]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.210. Nov 26 10:30:27 volumiopc1 dhcpcd[425]: eth0: adding route to 192.168.0.0/24 Nov 26 10:30:27 volumiopc1 dhcpcd[425]: eth0: adding default route via 192.168.0.1 Nov 26 10:30:27 volumiopc1 avahi-daemon[364]: New relevant interface eth0.IPv4 for mDNS. Nov 26 10:30:27 volumiopc1 avahi-daemon[364]: Registering new address record for 192.168.0.210 on eth0.IPv4. PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 07:28:53 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f11d51aa83fa91106999927181e8c0f7"