-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2025-07-05 22:30:08 JST. -- Jul 05 22:29:10 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 22:29:10 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:10 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:10 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 22:29:11 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:11 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:11 volumio volumio[656]: verbose: New Socket.io Connection to 192.168.2.47 from 192.168.2.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Total Clients: 4 Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:11 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 22:29:11 volumio volumio[656]: info: Received Get System Info Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 22:29:11 volumio volumio[656]: info: Discovery: Getting this device information Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:11 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:11 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:11 volumio volumio[656]: info: Listing playlists Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 22:29:11 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:12 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 22:29:12 volumio volumio[656]: info: Received Get System Info Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 22:29:12 volumio volumio[656]: info: Discovery: Getting this device information Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:12 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:12 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::volumioPlay Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::play index undefined Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::startPlaybackTimer Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::clearAddPlayTracks NAS/S2/Convert/_Flac/1/ひまわり''SUNWARD''.flac Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand stop took 1 milliseconds Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand clear took 1 milliseconds Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand add "NAS/S2/Convert/_Flac/1/ひまわり''SUNWARD''.flac" Jul 05 22:29:14 volumio volumio[656]: info: Jul 05 22:29:14 volumio volumio[656]: ---------------------------- MPD announces system playlist update Jul 05 22:29:14 volumio volumio[656]: info: Ignoring MPD Status Update Jul 05 22:29:14 volumio volumio[656]: info: Jul 05 22:29:14 volumio volumio[656]: ---------------------------- MPD announces system playlist update Jul 05 22:29:14 volumio volumio[656]: info: Ignoring MPD Status Update Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand add "NAS/S2/Convert/_Flac/1/ひまわり''SUNWARD''.flac" took 4 milliseconds Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand play Jul 05 22:29:14 volumio volumio[656]: error: Upnp client error: Error: This socket has been ended by the other party Jul 05 22:29:14 volumio volumio[656]: info: ------------------------------ 7ms Jul 05 22:29:14 volumio volumio[656]: info: ------------------------------ 6ms Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand play took 4 milliseconds Jul 05 22:29:14 volumio volumio[656]: info: Jul 05 22:29:14 volumio volumio[656]: ---------------------------- MPD announces state update: player Jul 05 22:29:14 volumio volumio[656]: info: ControllerMpd::getState Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand status Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand status took 5 milliseconds Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::parseState Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand clearerror took 3 milliseconds Jul 05 22:29:14 volumio volumio[656]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 22:29:14 volumio volumio[656]: verbose: ControllerMpd::parseTrackInfo Jul 05 22:29:14 volumio volumio[656]: info: ControllerMpd::pushState Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::servicePushState Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::pushState Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::volumioPushState Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ひまわり\"SUNWARD\"","artist":"中島みゆき","album":"LOVE OR NOTHING","uri":"NAS/S2/Convert/_Flac/1/ひまわり''SUNWARD''.flac","trackType":"flac"} Jul 05 22:29:14 volumio volumio[656]: verbose: CURRENT POSITION 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::syncState stateService stop Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::syncState currentStatus stop Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::pushState Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::volumioPushState Jul 05 22:29:14 volumio volumio[656]: info: No code Jul 05 22:29:14 volumio volumio[656]: info: CoreStateMachine::pushState Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::volumioPushState Jul 05 22:29:14 volumio volumio[656]: info: ------------------------------ 34ms Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 22:29:14 volumio volumio[656]: info: Received Get System Info Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 22:29:14 volumio volumio[656]: info: Discovery: Getting this device information Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:14 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:14 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 22:29:25 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:25 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 05 22:29:25 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 05 22:29:25 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 05 22:29:25 volumio dbus-daemon[482]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=1000 pid=1234 comm="timedatectl ") Jul 05 22:29:25 volumio systemd[1]: Starting Time & Date Service... Jul 05 22:29:25 volumio dbus-daemon[482]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 05 22:29:25 volumio systemd[1]: Started Time & Date Service. Jul 05 22:29:26 volumio volumio[656]: info: Received Get System Version Jul 05 22:29:26 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 22:29:31 volumio volumio[656]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 05 22:29:31 volumio volumio[656]: info: Received Get System Version Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 22:29:31 volumio volumio[656]: info: Received Get System Info Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 22:29:31 volumio volumio[656]: info: Discovery: Getting this device information Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:31 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:31 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 22:29:42 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:42 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:43 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:43 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 22:29:43 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jul 05 22:29:43 volumio sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jul 05 22:29:43 volumio sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 05 22:29:43 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:43 volumio sudo[1244]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:43 volumio sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 05 22:29:43 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:43 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jul 05 22:29:43 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:43 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 22:29:43 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jul 05 22:29:43 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jul 05 22:29:43 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:43 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 22:29:43 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:29:43 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Jul 05 22:29:49 volumio volumio[656]: info: CoreCommandRouter::volumioGetState Jul 05 22:29:49 volumio volumio[656]: info: CorePlayQueue::getTrack 0 Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: info: Retrieving Cloud Streaming UI Jul 05 22:29:53 volumio volumio[656]: info: Getting Tidal Cloud Configuration Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: info: Getting Qobuz Cloud Configuration Jul 05 22:29:53 volumio volumio[656]: info: Asking plugin for UI Config Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: info: Getting Spotify Cloud Configuration Jul 05 22:29:53 volumio volumio[656]: info: Asking plugin for UI Config Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: info: Saving Spotify Acccount Jul 05 22:29:53 volumio volumio[656]: info: Got Tidal Cloud Configuration Jul 05 22:29:53 volumio volumio[656]: info: Got it Jul 05 22:29:53 volumio volumio[656]: info: Got it Jul 05 22:29:53 volumio volumio[656]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 22:29:53 volumio volumio[656]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 22:29:53 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 22:29:56 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 05 22:29:57 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 22:30:01 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 22:30:05 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 22:30:07 volumio volumio[656]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jul 05 22:30:07 volumio sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/S1 Jul 05 22:30:07 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 22:30:07 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Jul 05 22:30:07 volumio volumio[656]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 22:30:07 volumio volumio[656]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/S1' Jul 05 22:30:07 volumio volumio[656]: at Object.rmdirSync (fs.js:890:10) Jul 05 22:30:07 volumio volumio[656]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jul 05 22:30:07 volumio volumio[656]: at ChildProcess.exithandler (child_process.js:299:7) Jul 05 22:30:07 volumio volumio[656]: at ChildProcess.emit (events.js:315:20) Jul 05 22:30:07 volumio volumio[656]: at maybeClose (internal/child_process.js:1048:16) Jul 05 22:30:07 volumio volumio[656]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) { Jul 05 22:30:07 volumio volumio[656]: errno: -16, Jul 05 22:30:07 volumio volumio[656]: syscall: 'rmdir', Jul 05 22:30:07 volumio volumio[656]: code: 'EBUSY', Jul 05 22:30:07 volumio volumio[656]: path: '/mnt/NAS/S1' Jul 05 22:30:07 volumio volumio[656]: } Jul 05 22:30:07 volumio volumio[656]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 22:30:08 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 22:29 Jul 05 22:30:08 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) 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="1d653e2fb41503057db8860c70e12ef24c2e719e" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="40eb0c247e5bd225374c0604272467dc3894b093" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 24 Jan 2023 03:45:04 PM CET" VOLUMIO_VERSION="3.423" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="55bacfcc93db94ca13e08c0544afcad8"