-- Logs begin at Sat 2025-05-10 01:39:50 JST, end at Sat 2025-05-10 14:50:40 JST. -- May 10 14:49:03 volumio2 volumio[956]: info: MYVOLUMIO Environment detected May 10 14:49:04 volumio2 volumio[956]: info: Plugin folders cleanup May 10 14:49:04 volumio2 volumio[956]: info: Scanning into folder /volumio/app/plugins/ May 10 14:49:04 volumio2 volumio[956]: info: Scanning category audio_interface May 10 14:49:04 volumio2 volumio[956]: info: Scanning category miscellanea May 10 14:49:04 volumio2 volumio[956]: info: Scanning category music_service May 10 14:49:04 volumio2 volumio[956]: info: Scanning category plugins.json May 10 14:49:04 volumio2 volumio[956]: info: Scanning category system_controller May 10 14:49:04 volumio2 volumio[956]: info: Scanning category user_interface May 10 14:49:04 volumio2 volumio[956]: info: Scanning into folder /data/plugins/ May 10 14:49:04 volumio2 volumio[956]: info: Plugin folders cleanup completed May 10 14:49:04 volumio2 volumio[956]: info: ------------------------------------------- May 10 14:49:04 volumio2 volumio[956]: info: ----- Core plugins startup ---- May 10 14:49:04 volumio2 volumio[956]: info: ------------------------------------------- May 10 14:49:04 volumio2 volumio[956]: info: Loading plugins from folder /volumio/app/plugins/ May 10 14:49:04 volumio2 volumio[956]: info: Adding plugin upnp to MyMusic Plugins May 10 14:49:04 volumio2 volumio[956]: info: Adding plugin airplay_emulation to MyMusic Plugins May 10 14:49:04 volumio2 volumio[956]: info: Adding plugin upnp_browser to MyMusic Plugins May 10 14:49:04 volumio2 volumio[956]: info: Loading plugins from folder /data/plugins/ May 10 14:49:04 volumio2 volumio[956]: info: Loading plugin "system"... May 10 14:49:05 volumio2 volumio[956]: info: Loading plugin "appearance"... May 10 14:49:06 volumio2 ntpd[559]: Soliciting pool server 129.250.35.250 May 10 14:49:07 volumio2 ntpd[559]: Soliciting pool server 139.162.81.45 May 10 14:49:07 volumio2 ntpd[559]: Soliciting pool server 129.250.35.251 May 10 14:49:08 volumio2 ntpd[559]: Soliciting pool server 172.237.12.44 May 10 14:49:08 volumio2 ntpd[559]: Soliciting pool server 45.76.221.157 May 10 14:49:09 volumio2 ntpd[559]: Soliciting pool server 162.159.200.123 May 10 14:49:09 volumio2 ntpd[559]: Soliciting pool server 122.215.240.51 May 10 14:49:09 volumio2 ntpd[559]: Soliciting pool server 202.181.103.212 May 10 14:49:09 volumio2 ntpd[559]: Soliciting pool server 162.159.200.1 May 10 14:49:10 volumio2 ntpd[559]: Soliciting pool server 45.76.211.39 May 10 14:49:10 volumio2 ntpd[559]: Soliciting pool server 160.16.113.133 May 10 14:49:10 volumio2 ntpd[559]: Soliciting pool server 23.81.40.154 May 10 14:49:10 volumio2 ntpd[559]: Soliciting pool server 64.176.59.83 May 10 14:49:10 volumio2 volumio-remote-updater[398]: [2025-05-10 14:49:10] [connect] Successful connection May 10 14:49:11 volumio2 ntpd[559]: Soliciting pool server 108.160.132.224 May 10 14:49:11 volumio2 ntpd[559]: Soliciting pool server 103.131.151.30 May 10 14:49:11 volumio2 ntpd[559]: Soliciting pool server 133.130.121.141 May 10 14:49:12 volumio2 ntpd[559]: Soliciting pool server 2001:418:3ff::53 May 10 14:49:12 volumio2 ntpd[559]: Soliciting pool server 103.131.151.20 May 10 14:49:12 volumio2 volumio[956]: info: Loading plugin "network"... May 10 14:49:12 volumio2 volumio[956]: info: Refreshing Cached IP Addresses May 10 14:49:13 volumio2 sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 14:49:13 volumio2 sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 14:49:13 volumio2 sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:13 volumio2 volumio[956]: info: Loading plugin "services"... May 10 14:49:13 volumio2 sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:13 volumio2 volumio[956]: info: Loading plugin "alsa_controller"... May 10 14:49:13 volumio2 sudo[1072]: pam_unix(sudo:session): session closed for user root May 10 14:49:13 volumio2 sudo[1074]: pam_unix(sudo:session): session closed for user root May 10 14:49:13 volumio2 sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 10 14:49:13 volumio2 sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:14 volumio2 sudo[1078]: pam_unix(sudo:session): session closed for user root May 10 14:49:14 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 14:49:14 volumio2 volumio[956]: info: Loading plugin "wizard"... May 10 14:49:14 volumio2 volumio[956]: info: Loading plugin "networkfs"... May 10 14:49:15 volumio2 volumio[956]: info: Starting Udev Watcher for removable devices May 10 14:49:15 volumio2 sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=volumio,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.30/USB /mnt/NAS/USB May 10 14:49:15 volumio2 sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:21 volumio2 ntpd[559]: receive: Unexpected origin timestamp 0xebc9675b.5193e289 does not match aorg 0000000000.00000000 from server@129.250.35.250 xmt 0xebc96761.8009d236 May 10 14:49:21 volumio2 ntpd[559]: receive: Unexpected origin timestamp 0xebc9675b.512b44ad does not match aorg 0000000000.00000000 from server@64.176.59.83 xmt 0xebc96761.805ce8dc May 10 14:49:21 volumio2 systemd[1]: Starting Daily apt download activities... May 10 14:49:21 volumio2 volumio[956]: info: Ignoring mount for partition: boot May 10 14:49:21 volumio2 volumio[956]: info: Ignoring mount for partition: volumio May 10 14:49:21 volumio2 volumio[956]: info: Ignoring mount for partition: volumio_data May 10 14:49:21 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 14:49:21 volumio2 volumio[956]: info: Loading plugin "volumio_command_line_client"... May 10 14:49:22 volumio2 volumio[956]: info: Loading plugin "upnp"... May 10 14:49:22 volumio2 volumio[956]: info: [1746856162237] Starting Upmpd Daemon May 10 14:49:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 14:49:22 volumio2 volumio[956]: info: Loading plugin "my_music"... May 10 14:49:22 volumio2 volumio[956]: info: Loading plugin "mpd"... May 10 14:49:23 volumio2 kernel: Key type cifs.spnego registered May 10 14:49:23 volumio2 kernel: Key type cifs.idmap registered May 10 14:49:23 volumio2 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 10 14:49:23 volumio2 kernel: CIFS: Attempting to mount //192.168.0.30/USB May 10 14:49:26 volumio2 sudo[1104]: pam_unix(sudo:session): session closed for user root May 10 14:49:28 volumio2 volumio[956]: info: Loading plugin "upnp_browser"... May 10 14:49:32 volumio2 volumio-remote-updater[398]: [2025-05-10 14:49:32] [connect] Successful connection May 10 14:49:35 volumio2 volumio[956]: info: Loading plugin "alarm-clock"... May 10 14:49:37 volumio2 systemd[1]: apt-daily.service: Succeeded. May 10 14:49:37 volumio2 systemd[1]: Started Daily apt download activities. May 10 14:49:37 volumio2 volumio[956]: info: Loading plugin "airplay_emulation"... May 10 14:49:37 volumio2 volumio[956]: info: Starting Shairport Sync May 10 14:49:37 volumio2 volumio[956]: info: Loading plugin "last_100"... May 10 14:49:38 volumio2 volumio[956]: info: Loading plugin "webradio"... May 10 14:49:38 volumio2 volumio[956]: info: Loading plugin "i2s_dacs"... May 10 14:49:38 volumio2 volumio[956]: info: I2S DAC not set, start Auto-detection May 10 14:49:38 volumio2 volumio[956]: info: Loading plugin "volumiodiscovery"... May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 10 14:49:39 volumio2 node[956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** For more information see May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 14:49:39 volumio2 volumio[956]: *** WARNING *** For more information see May 10 14:49:39 volumio2 node[956]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 14:49:39 volumio2 node[956]: *** WARNING *** For more information see May 10 14:49:39 volumio2 node[956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 10 14:49:39 volumio2 node[956]: *** WARNING *** Please fix your application to use the native API of Avahi! May 10 14:49:39 volumio2 node[956]: *** WARNING *** For more information see May 10 14:49:39 volumio2 volumio[956]: info: Applying required configuration parameters for plugin volumiodiscovery May 10 14:49:39 volumio2 volumio[956]: info: Discovery: Started advertising with name: Volumio2 May 10 14:49:39 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 14:49:39 volumio2 volumio[956]: info: Loading plugin "outputs"... May 10 14:49:39 volumio2 volumio[956]: info: Loading plugin "albumart"... May 10 14:49:39 volumio2 volumio[956]: info: Plugin example_plugin is not enabled May 10 14:49:39 volumio2 volumio[956]: info: Loading plugin "inputs"... May 10 14:49:39 volumio2 volumio[956]: info: Loading plugin "updater_comm"... May 10 14:49:42 volumio2 volumio[956]: info: Plugin mpdemulation is not enabled May 10 14:49:42 volumio2 volumio[956]: info: Loading plugin "rest_api"... May 10 14:49:42 volumio2 volumio[956]: info: Loading plugin "websocket"... May 10 14:49:42 volumio2 volumio[956]: info: Starting Socket.io Server version 2.3.0 May 10 14:49:43 volumio2 volumio[956]: info: Loading i18n strings for locale ja May 10 14:49:43 volumio2 volumio[956]: Updating browse sources language May 10 14:49:43 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 14:49:44 volumio2 volumio[956]: Forking 1 albumart workers May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::initPlayerControls May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:49:46 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 14:49:46 volumio2 volumio[956]: Express server listening on port 3000 May 10 14:49:46 volumio2 volumio[956]: [Metrics] WebUI: 46s 901.80ms May 10 14:49:47 volumio2 volumio-remote-updater[398]: [2025-05-10 14:49:47] [connect] Successful connection May 10 14:49:47 volumio2 volumio[956]: info: CoreStateMachine::resetVolumioState May 10 14:49:47 volumio2 volumio[956]: info: CoreStateMachine::getcurrentVolume May 10 14:49:47 volumio2 volumio[956]: info: CoreCommandRouter::volumioRetrievevolume May 10 14:49:47 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:49:48 volumio2 sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 14:49:48 volumio2 sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 14:49:48 volumio2 sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:48 volumio2 sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:48 volumio2 sudo[1253]: pam_unix(sudo:session): session closed for user root May 10 14:49:48 volumio2 sudo[1255]: pam_unix(sudo:session): session closed for user root May 10 14:49:48 volumio2 volumio[956]: info: Volumio Network Manager: Network status updated: 1 May 10 14:49:49 volumio2 volumio[956]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 10 14:49:49 volumio2 volumio[956]: wlan0 Interface doesn't support scanning. May 10 14:49:49 volumio2 volumio[956]: info: Cannot use regular scanning, forcing with ap-force May 10 14:49:49 volumio2 sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 10 14:49:49 volumio2 sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:49 volumio2 sudo[1262]: pam_unix(sudo:session): session closed for user root May 10 14:49:49 volumio2 volumio[956]: command failed: No such device (-19) May 10 14:49:49 volumio2 volumio[956]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 10 14:49:49 volumio2 volumio[956]: command failed: No such device (-19) May 10 14:49:51 volumio2 volumio[956]: verbose: New Socket.io Connection to 192.168.0.32:3000 from 192.168.0.30 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 10 14:49:51 volumio2 volumio[956]: verbose: New Socket.io Connection to 192.168.0.32:3000 from 192.168.0.30 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 10 14:49:51 volumio2 volumio-remote-updater[398]: [2025-05-10 14:49:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746856187 101 May 10 14:49:51 volumio2 volumio[956]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 10 14:49:51 volumio2 volumio[956]: info: VolumeController:: Volume=30 Mute =false May 10 14:49:51 volumio2 volumio[956]: info: CoreStateMachine::pushState May 10 14:49:51 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:51 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 14:49:51 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushState May 10 14:49:51 volumio2 volumio[956]: info: CoreStateMachine::updateTrackBlock May 10 14:49:51 volumio2 volumio[956]: info: CorePlayQueue::getTrackBlock May 10 14:49:51 volumio2 volumio[956]: info: CoreCommandRouter::volumioRetrievevolume May 10 14:49:52 volumio2 volumio[956]: info: Reloading queue from file May 10 14:49:53 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 10 14:49:53 volumio2 volumio[956]: info: Setting Device type: Raspberry PI May 10 14:49:53 volumio2 volumio[956]: info: CoreStateMachine::setRepeat null single undefined May 10 14:49:53 volumio2 volumio[956]: info: CoreStateMachine::pushState May 10 14:49:53 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:53 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 14:49:53 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushState May 10 14:49:53 volumio2 volumio[956]: info: CoreStateMachine::setRandom null May 10 14:49:53 volumio2 volumio[956]: info: CoreStateMachine::pushState May 10 14:49:53 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:53 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushState May 10 14:49:53 volumio2 volumio[956]: info: CoreCommandRouter::volumioGetState May 10 14:49:53 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:54 volumio2 volumio[956]: info: Discovery: adding cfc2eae1-5446-4f41-97f8-25b04fdb663f May 10 14:49:54 volumio2 volumio[956]: info: Discovery: Found device Volumio May 10 14:49:54 volumio2 sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 10 14:49:54 volumio2 sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:54 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. May 10 14:49:54 volumio2 sudo[1289]: pam_unix(sudo:session): session closed for user root May 10 14:49:55 volumio2 volumio[956]: info: Discovery: Connecting to remote: 192.168.0.30 May 10 14:49:55 volumio2 volumio[956]: info: Upmpdcli Daemon Started May 10 14:49:55 volumio2 volumio[956]: info: VolumeController:: Volume=30 Mute =false May 10 14:49:55 volumio2 volumio[956]: info: CoreStateMachine::pushState May 10 14:49:55 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:55 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushState May 10 14:49:55 volumio2 volumio[956]: info: Discovery: adding 888a0689-2307-471b-abe1-ccd9ee3d9a63 May 10 14:49:55 volumio2 volumio[956]: info: Discovery: Found device Volumio2 May 10 14:49:55 volumio2 volumio[956]: info: CoreCommandRouter::volumioGetState May 10 14:49:55 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:49:56 volumio2 volumio[956]: info: Discovery: Connected to remote: 192.168.0.30 May 10 14:49:56 volumio2 volumio[956]: info: Completed loading Core Plugins May 10 14:49:56 volumio2 volumio[956]: info: Preparing to generate the ALSA configuration file May 10 14:49:56 volumio2 volumio[956]: Starting albumart workers May 10 14:49:57 volumio2 volumio[956]: info: Asound.conf file unchanged, so no further update is needed May 10 14:49:57 volumio2 volumio[956]: info: Output device has changed, restarting MPD May 10 14:49:57 volumio2 volumio[1294]: Generating RSA private key, 4096 bit long modulus (2 primes) May 10 14:49:57 volumio2 volumio[956]: info: Output device has changed, restarting Shairport Sync May 10 14:49:57 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:49:58 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:49:58 volumio2 sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 14:49:58 volumio2 sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:58 volumio2 sudo[1309]: pam_unix(sudo:session): session closed for user root May 10 14:49:58 volumio2 sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 14:49:58 volumio2 sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:49:59 volumio2 systemd[1]: Stopping Music Player Daemon... May 10 14:50:00 volumio2 systemd[1]: mpd.service: Succeeded. May 10 14:50:00 volumio2 volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 14:50:00 volumio2 systemd[1]: Stopped Music Player Daemon. May 10 14:50:00 volumio2 systemd[1]: Starting Music Player Daemon... May 10 14:50:00 volumio2 volumio[956]: info: ___________ START PLUGINS ___________ May 10 14:50:00 volumio2 volumio[956]: info: ControllerMpd::onStart: Initializing MPD May 10 14:50:00 volumio2 volumio[956]: info: Creating MPD Configuration file May 10 14:50:01 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 14:50:02 volumio2 volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 14:50:02 volumio2 volumio[956]: info: [1746856202102] CoreMusicLibrary::Adding element DLNAサーバー May 10 14:50:02 volumio2 sudo[1318]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 14:50:02 volumio2 sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:02 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 14:50:02 volumio2 sudo[1318]: pam_unix(sudo:session): session closed for user root May 10 14:50:02 volumio2 sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 10 14:50:02 volumio2 sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:03 volumio2 sudo[1329]: pam_unix(sudo:session): session closed for user root May 10 14:50:03 volumio2 sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 10 14:50:03 volumio2 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:04 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 10 14:50:04 volumio2 systemd[1]: mpd.service: Succeeded. May 10 14:50:04 volumio2 systemd[1]: Stopped Music Player Daemon. May 10 14:50:04 volumio2 systemd[1]: Starting Music Player Daemon... May 10 14:50:04 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:04 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:50:06 volumio2 sudo[1380]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 14:50:06 volumio2 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:06 volumio2 sudo[1380]: pam_unix(sudo:session): session closed for user root May 10 14:50:06 volumio2 volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 14:50:06 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:06 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:50:06 volumio2 mpd_monitor.sh[421]: mpd: no process found May 10 14:50:07 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 10 14:50:07 volumio2 systemd[1]: mpd.service: Succeeded. May 10 14:50:07 volumio2 systemd[1]: Stopped Music Player Daemon. May 10 14:50:07 volumio2 systemd[1]: Starting Music Player Daemon... May 10 14:50:08 volumio2 volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 10 14:50:08 volumio2 volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 14:50:08 volumio2 volumio[956]: info: [1746856208180] CoreMusicLibrary::Adding element Last_100 May 10 14:50:08 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 14:50:08 volumio2 volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 10 14:50:08 volumio2 volumio[956]: info: [1746856208290] CoreMusicLibrary::Adding element Webradio May 10 14:50:08 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 14:50:08 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 14:50:08 volumio2 volumio[956]: info: Initializing BBC Radios May 10 14:50:08 volumio2 sudo[1391]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 10 14:50:08 volumio2 sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:08 volumio2 sudo[1391]: pam_unix(sudo:session): session closed for user root May 10 14:50:10 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 14:50:10 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:50:10 volumio2 volumio[956]: info: Volumio Calling Home May 10 14:50:17 volumio2 volumio[956]: error: Upnp client error: Error: This socket has been ended by the other party May 10 14:50:18 volumio2 volumio[956]: info: MPD Permissions set May 10 14:50:18 volumio2 volumio[956]: info: MPD Permissions set May 10 14:50:20 volumio2 volumio[956]: info: Volumio called home May 10 14:50:20 volumio2 volumio[956]: info: May 10 14:50:20 volumio2 volumio[956]: ---------------------------- Client requests Volumio Play Playlist chime May 10 14:50:20 volumio2 volumio[956]: info: Play playlist chime May 10 14:50:22 volumio2 volumio[956]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:22 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:23 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:23 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 10 14:50:23 volumio2 volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 10 14:50:24 volumio2 volumio[956]: info: Starting Shairport Sync May 10 14:50:24 volumio2 volumio[956]: info: Starting Shairport Sync May 10 14:50:24 volumio2 volumio[956]: info: Starting Shairport Sync May 10 14:50:25 volumio2 sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 14:50:25 volumio2 sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:25 volumio2 volumio[956]: info: CoreCommandRouter::volumioGetState May 10 14:50:25 volumio2 sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 14:50:25 volumio2 volumio[956]: info: CorePlayQueue::getTrack 0 May 10 14:50:25 volumio2 sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:26 volumio2 volumio[956]: info: CoreCommandRouter::volumioClearQueue May 10 14:50:26 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 14:50:26 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 10 14:50:26 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 14:50:26 volumio2 volumio[956]: info: CoreStateMachine::ClearQueue May 10 14:50:26 volumio2 sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 10 14:50:26 volumio2 volumio[956]: info: CoreStateMachine::stop May 10 14:50:26 volumio2 sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:26 volumio2 volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined May 10 14:50:26 volumio2 volumio[956]: info: CorePlayQueue::clearPlayQueue May 10 14:50:26 volumio2 volumio[956]: info: CorePlayQueue::saveQueue May 10 14:50:26 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushState May 10 14:50:26 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 14:50:26 volumio2 sudo[1439]: pam_unix(sudo:session): session closed for user root May 10 14:50:27 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 14:50:27 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 10 14:50:27 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 10 14:50:27 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 14:50:27 volumio2 volumio[956]: info: CoreCommandRouter::volumioPushQueue May 10 14:50:27 volumio2 volumio[956]: info: CoreCommandRouter::volumioAddQueueItems May 10 14:50:27 volumio2 volumio[956]: info: CoreStateMachine::addQueueItems May 10 14:50:27 volumio2 volumio[956]: info: CorePlayQueue::addQueueItems May 10 14:50:27 volumio2 volumio[956]: info: Preload queue cleared May 10 14:50:27 volumio2 volumio[956]: info: Adding Item to queue: mnt/INTERNAL/chime.mp3 May 10 14:50:27 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 14:50:27 volumio2 sudo[1441]: pam_unix(sudo:session): session closed for user root May 10 14:50:27 volumio2 volumio[956]: info: Exploding uri mnt/INTERNAL/chime.mp3 in service mpd May 10 14:50:28 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 10 14:50:28 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 10 14:50:28 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 10 14:50:28 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 10 14:50:28 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 10 14:50:28 volumio2 sudo[1443]: pam_unix(sudo:session): session closed for user root May 10 14:50:28 volumio2 volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 14:50:29 volumio2 volumio[956]: TypeError: Cannot read property 'then' of undefined May 10 14:50:29 volumio2 volumio[956]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) May 10 14:50:29 volumio2 volumio[956]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) May 10 14:50:29 volumio2 volumio[956]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1378:16) May 10 14:50:29 volumio2 volumio[956]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 10 14:50:29 volumio2 volumio[956]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 10 14:50:29 volumio2 volumio[956]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 10 14:50:29 volumio2 volumio[956]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 10 14:50:29 volumio2 volumio[956]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) May 10 14:50:29 volumio2 volumio[956]: at /volumio/app/playlistManager.js:726:30 May 10 14:50:29 volumio2 volumio[956]: at /volumio/node_modules/jsonfile/index.js:46:5 May 10 14:50:29 volumio2 volumio[956]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 May 10 14:50:29 volumio2 volumio[956]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) May 10 14:50:29 volumio2 volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 14:50:40 volumio2 sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 14:49 May 10 14:50:40 volumio2 sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 14:50:40 volumio2 mpd[1395]: May 10 14:50 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"