-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-11-21 19:53:37 UTC. --
Nov 21 19:52:01 volumio volumio[1081]: info: CoreCommandRouter::Close All Modals sent
Nov 21 19:52:08 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState
Nov 21 19:52:08 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:13 volumio volumio-remote-updater[473]: No test mode
Nov 21 19:52:13 volumio volumio-remote-updater[473]: No alpha test mode
Nov 21 19:52:13 volumio volumio[1081]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
- Fix Multiroom Buffer Sizes
- Increase results for Kana-Kanji Conversion
- Fix for streaming interruptions
NEW ADDITIONS
- Bump RPi kernel to 6.6.56, support for RPi5 2GB
- Dedicated entry for RPi5 on some I2S DACs, restored master mode for older RPi models
- Add support for HifiBerry AMP4 Pro on RPi
","title":"Update v3.779","updateavailable":true}
Nov 21 19:52:13 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Nov 21 19:52:24 volumio volumio[1081]: info: Update: [object Object]
Nov 21 19:52:24 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity
Nov 21 19:52:24 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , setTestSystem
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkUpdates
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Nov 21 19:52:28 volumio volumio-remote-updater[473]: PROGRESS: 0, STATUS: "Starting update", ETA: "6m"
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: updater_comm , notifyProgress
Nov 21 19:52:28 volumio volumio-remote-updater[473]: /bin/rm: cannot remove '/imgpart/rck*': No such file or directory
Nov 21 19:52:28 volumio volumio-remote-updater[473]: /bin/rm: cannot remove '/imgpart/*.part': No such file or directory
Nov 21 19:52:28 volumio volumio-remote-updater[473]: /bin/rm: cannot remove '/imgpart/*.zs-old': No such file or directory
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Nov 21 19:52:28 volumio volumio-remote-updater[473]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Nov 21 19:52:28 volumio volumio[1081]: info: Killing processes that might interfere with OTA Updates
Nov 21 19:52:28 volumio sudo[7833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-keyboard
Nov 21 19:52:28 volumio sudo[7833]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Nov 21 19:52:28 volumio volumio[1081]: updateProgress
Nov 21 19:52:28 volumio volumio[1081]: {
Nov 21 19:52:28 volumio volumio[1081]: downloadSpeed: '',
Nov 21 19:52:28 volumio volumio[1081]: eta: '5m',
Nov 21 19:52:28 volumio volumio[1081]: progress: 5,
Nov 21 19:52:28 volumio volumio[1081]: status: 'Aggiornamento in preparazione'
Nov 21 19:52:28 volumio volumio[1081]: }
Nov 21 19:52:28 volumio sudo[7836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall matchbox-window-manager
Nov 21 19:52:28 volumio sudo[7836]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 21 19:52:28 volumio sudo[7833]: pam_unix(sudo:session): session closed for user root
Nov 21 19:52:28 volumio sudo[7836]: pam_unix(sudo:session): session closed for user root
Nov 21 19:52:28 volumio volumio[1081]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-keyboard
Nov 21 19:52:28 volumio volumio[1081]: matchbox-keyboard: no process found
Nov 21 19:52:28 volumio volumio[1081]: error: Cannot kill process: Error: Command failed: /usr/bin/sudo /usr/bin/killall matchbox-window-manager
Nov 21 19:52:28 volumio volumio[1081]: matchbox-window-manager: no process found
Nov 21 19:52:28 volumio volumio-remote-updater[473]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Nov 21 19:52:28 volumio volumio-remote-updater[473]: PROGRESS: 5, STATUS: "Preparing update", ETA: "5m"
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Nov 21 19:52:28 volumio volumio[1081]: updateProgress
Nov 21 19:52:28 volumio volumio[1081]: {
Nov 21 19:52:28 volumio volumio[1081]: downloadSpeed: '',
Nov 21 19:52:28 volumio volumio[1081]: eta: '5m',
Nov 21 19:52:28 volumio volumio[1081]: progress: 5,
Nov 21 19:52:28 volumio volumio[1081]: status: 'Aggiornamento in preparazione'
Nov 21 19:52:28 volumio volumio[1081]: }
Nov 21 19:52:28 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Nov 21 19:52:28 volumio volumio[1081]: updateProgress
Nov 21 19:52:28 volumio volumio[1081]: {
Nov 21 19:52:28 volumio volumio[1081]: downloadSpeed: '',
Nov 21 19:52:28 volumio volumio[1081]: eta: '5m',
Nov 21 19:52:28 volumio volumio[1081]: progress: 5,
Nov 21 19:52:28 volumio volumio[1081]: status: 'Aggiornamento in preparazione'
Nov 21 19:52:28 volumio volumio[1081]: }
Nov 21 19:52:29 volumio volumio[1081]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Nov 21 19:52:30 volumio volumio[1081]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object]
Nov 21 19:52:30 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage
Nov 21 19:52:38 volumio volumio[1081]: info: CoreCommandRouter::Close All Modals sent
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioClearQueue
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::ClearQueue
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::stop
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::stPlaybackTimer
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::pushState
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::serviceStop
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::serviceStop
Nov 21 19:52:47 volumio volumio[1081]: info: ControllerMpd::stop
Nov 21 19:52:47 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::saveQueue
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue
Nov 21 19:52:47 volumio volumio[1081]: info:
Nov 21 19:52:47 volumio volumio[1081]: ---------------------------- MPD announces state update: player
Nov 21 19:52:47 volumio volumio[1081]: info: sendMpdCommand stop took 15 milliseconds
Nov 21 19:52:47 volumio volumio[1081]: info: ControllerMpd::getState
Nov 21 19:52:47 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status
Nov 21 19:52:47 volumio volumio[1081]: info: sendMpdCommand status took 3 milliseconds
Nov 21 19:52:47 volumio volumio[1081]: verbose: ControllerMpd::parseState
Nov 21 19:52:47 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Nov 21 19:52:47 volumio volumio[1081]: error: Upnp client error: Error: This socket has been ended by the other party
Nov 21 19:52:47 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 2 milliseconds
Nov 21 19:52:47 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo
Nov 21 19:52:47 volumio volumio[1081]: info: ControllerMpd::pushState
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::servicePushState
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::pushState
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: 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":"Daydreamer","artist":"Adele","album":"19","uri":"NAS/FRITZ.NAS/Adele - (2008) - 19/01 - Daydreamer - Adele.flac","trackType":"flac"}
Nov 21 19:52:47 volumio volumio[1081]: verbose: CURRENT POSITION 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::syncState stateService stop
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::pushState
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState
Nov 21 19:52:47 volumio volumio[1081]: info: No code
Nov 21 19:52:47 volumio volumio[1081]: info: CoreStateMachine::pushState
Nov 21 19:52:47 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:52:47 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState
Nov 21 19:52:47 volumio volumio[1081]: info: ------------------------------ 33ms
Nov 21 19:52:56 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState
Nov 21 19:52:56 volumio volumio[1081]: info: CorePlayQueue::getTrack 0
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: info: Retrieving Cloud Streaming UI
Nov 21 19:53:06 volumio volumio[1081]: info: Getting Tidal Cloud Configuration
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: info: Getting Qobuz Cloud Configuration
Nov 21 19:53:06 volumio volumio[1081]: info: Asking plugin for UI Config
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: info: Getting Spotify Cloud Configuration
Nov 21 19:53:06 volumio volumio[1081]: info: Asking plugin for UI Config
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: info: Saving Spotify Acccount
Nov 21 19:53:06 volumio volumio[1081]: info: Got Tidal Cloud Configuration
Nov 21 19:53:06 volumio volumio[1081]: info: Got it
Nov 21 19:53:06 volumio volumio[1081]: info: Got it
Nov 21 19:53:06 volumio volumio[1081]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin
Nov 21 19:53:06 volumio volumio[1081]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::volumioGetBrowseSources
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:06 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Nov 21 19:53:10 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:14 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:18 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:22 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:26 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:30 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:34 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Nov 21 19:53:37 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
Nov 21 19:53:37 volumio sudo[7870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/FRITZ.NAS
Nov 21 19:53:37 volumio sudo[7870]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 21 19:53:37 volumio sudo[7870]: pam_unix(sudo:session): session closed for user root
Nov 21 19:53:37 volumio volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 21 19:53:37 volumio volumio[1081]: Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/FRITZ.NAS'
Nov 21 19:53:37 volumio volumio[1081]: at Object.rmdirSync (fs.js:890:10)
Nov 21 19:53:37 volumio volumio[1081]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12
Nov 21 19:53:37 volumio volumio[1081]: at ChildProcess.exithandler (child_process.js:299:7)
Nov 21 19:53:37 volumio volumio[1081]: at ChildProcess.emit (events.js:315:20)
Nov 21 19:53:37 volumio volumio[1081]: at maybeClose (internal/child_process.js:1048:16)
Nov 21 19:53:37 volumio volumio[1081]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) {
Nov 21 19:53:37 volumio volumio[1081]: errno: -16,
Nov 21 19:53:37 volumio volumio[1081]: syscall: 'rmdir',
Nov 21 19:53:37 volumio volumio[1081]: code: 'EBUSY',
Nov 21 19:53:37 volumio volumio[1081]: path: '/mnt/NAS/FRITZ.NAS'
Nov 21 19:53:37 volumio volumio[1081]: }
Nov 21 19:53:37 volumio volumio[1081]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Nov 21 19:53:37 volumio sudo[7882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 19:52
Nov 21 19:53:37 volumio sudo[7882]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4"
VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463"
VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7"
VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST"
VOLUMIO_VERSION="3.449"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"