-- Logs begin at Sun 2024-04-14 16:18:07 KST, end at Thu 2024-04-18 14:13:34 KST. --
Apr 18 14:12:00 volumiopi volumio-remote-updater[530]: Test mode enabled
Apr 18 14:12:00 volumiopi volumio-remote-updater[530]: No alpha test mode
Apr 18 14:12:00 volumiopi volumio-remote-updater[530]: Test mode enabled
Apr 18 14:12:00 volumiopi volumio-remote-updater[530]: No alpha test mode
Apr 18 14:12:01 volumiopi volumio[1593]: info: [now-playing] Fetching API key...
Apr 18 14:12:01 volumiopi volumio[1593]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for mpd crashing with wrongly formatted cue files
\nRestore install-to-disk on x86 and ThinkerBoard
\nFix for Waveshare 11.9inch DSI Display touch panel on RPi
\nFix for missing metadata on CD playback
\nFix for metadata with Classic and Modern UI
\nFix for factory-reset from USB on Motivo
\nFix for Volumio logo on boot on Motivo
\nVarious fixes for TIDAL Connect
\n
\nNEW ADDITIONS
\n\nBump MPD to 0.23.15-3
\nAdd support for Waveshare 4.0inch DSI Display on RPi
\nImprovements for reducing boot time
\nAdd patched driver for ax88179 on RPi
\nUNSTABLE ALPHA BUILD - DO NOT INSTALL
\n\n","title":"Update v3.649","updateavailable":true}
Apr 18 14:12:01 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 18 14:12:01 volumiopi volumio[1593]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for mpd crashing with wrongly formatted cue files
\nRestore install-to-disk on x86 and ThinkerBoard
\nFix for Waveshare 11.9inch DSI Display touch panel on RPi
\nFix for missing metadata on CD playback
\nFix for metadata with Classic and Modern UI
\nFix for factory-reset from USB on Motivo
\nFix for Volumio logo on boot on Motivo
\nVarious fixes for TIDAL Connect
\n
\nNEW ADDITIONS
\n\nBump MPD to 0.23.15-3
\nAdd support for Waveshare 4.0inch DSI Display on RPi
\nImprovements for reducing boot time
\nAdd patched driver for ax88179 on RPi
\nUNSTABLE ALPHA BUILD - DO NOT INSTALL
\n\n","title":"Update v3.649","updateavailable":true}
Apr 18 14:12:01 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 18 14:12:01 volumiopi volumio[1593]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for mpd crashing with wrongly formatted cue files
\nRestore install-to-disk on x86 and ThinkerBoard
\nFix for Waveshare 11.9inch DSI Display touch panel on RPi
\nFix for missing metadata on CD playback
\nFix for metadata with Classic and Modern UI
\nFix for factory-reset from USB on Motivo
\nFix for Volumio logo on boot on Motivo
\nVarious fixes for TIDAL Connect
\n
\nNEW ADDITIONS
\n\nBump MPD to 0.23.15-3
\nAdd support for Waveshare 4.0inch DSI Display on RPi
\nImprovements for reducing boot time
\nAdd patched driver for ax88179 on RPi
\nUNSTABLE ALPHA BUILD - DO NOT INSTALL
\n\n","title":"Update v3.649","updateavailable":true}
Apr 18 14:12:01 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 18 14:12:02 volumiopi volumio[1593]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Could not obtain API key
Apr 18 14:12:02 volumiopi volumio[1593]:     at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19)
Apr 18 14:12:02 volumiopi volumio[1593]:     at runMicrotasks ()
Apr 18 14:12:02 volumiopi volumio[1593]:     at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 18 14:12:02 volumiopi volumio[1593]:     at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:28)
Apr 18 14:12:02 volumiopi volumio[1593]: (node:1593) UnhandledPromiseRejectionWarning: Error: Could not obtain API key
Apr 18 14:12:02 volumiopi volumio[1593]:     at doGet (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:124:19)
Apr 18 14:12:02 volumiopi volumio[1593]:     at runMicrotasks ()
Apr 18 14:12:02 volumiopi volumio[1593]:     at processTicksAndRejections (internal/process/task_queues.js:93:5)
Apr 18 14:12:02 volumiopi volumio[1593]: (node:1593) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1689)
Apr 18 14:12:05 volumiopi volumio[1593]: info: Discovery: A device disappeared from network
Apr 18 14:12:05 volumiopi volumio[1593]: info: Discovery: Device pipofi disappeared from network
Apr 18 14:12:05 volumiopi volumio[1593]: info: Discovery: Disconnected from remote: 192.168.0.21
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 18 14:12:08 volumiopi volumio[1593]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 18 14:12:08 volumiopi volumio[1593]: info: Received Get System Version
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 18 14:12:08 volumiopi volumio[1593]: info: Received Get System Info
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 18 14:12:08 volumiopi volumio[1593]: info: Discovery: Getting this device information
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:08 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:08 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 18 14:12:28 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 18 14:12:28 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 18 14:12:30 volumiopi sudo[27684]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 18 14:12:30 volumiopi sudo[27684]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 18 14:12:30 volumiopi sudo[27684]: pam_unix(sudo:session): session closed for user root
Apr 18 14:12:30 volumiopi sudo[27687]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 18 14:12:30 volumiopi sudo[27687]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 18 14:12:30 volumiopi sudo[27687]: pam_unix(sudo:session): session closed for user root
Apr 18 14:12:30 volumiopi volumio[1593]: verbose: New Socket.io Connection to 192.168.0.5 from 192.168.0.45 UA: okhttp/4.9.2 Total Clients: 7
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:30 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 18 14:12:30 volumiopi volumio[1593]: info: Received Get System Info
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 18 14:12:30 volumiopi volumio[1593]: info: Discovery: Getting this device information
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:30 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:30 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:30 volumiopi volumio[1593]: info: Listing playlists
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 18 14:12:30 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 18 14:12:32 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 18 14:12:32 volumiopi volumio[1593]: info: Received Get System Info
Apr 18 14:12:32 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 18 14:12:32 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 18 14:12:32 volumiopi volumio[1593]: info: Discovery: Getting this device information
Apr 18 14:12:32 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:32 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:32 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 18 14:12:33 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 18 14:12:33 volumiopi volumio[1593]: info: Received Get System Info
Apr 18 14:12:33 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 18 14:12:33 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 18 14:12:33 volumiopi volumio[1593]: info: Discovery: Getting this device information
Apr 18 14:12:33 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:33 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:33 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 18 14:12:35 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetQueue
Apr 18 14:12:35 volumiopi volumio[1593]: info: CoreStateMachine::getQueue
Apr 18 14:12:35 volumiopi volumio[1593]: info: CorePlayQueue::getQueue
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 18 14:12:49 volumiopi volumio[1593]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 18 14:12:49 volumiopi volumio[1593]: info: Received Get System Version
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 18 14:12:49 volumiopi volumio[1593]: info: Received Get System Info
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 18 14:12:49 volumiopi volumio[1593]: info: Discovery: Getting this device information
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::volumioGetState
Apr 18 14:12:49 volumiopi volumio[1593]: info: CorePlayQueue::getTrack 0
Apr 18 14:12:49 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 18 14:13:03 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 18 14:13:03 volumiopi volumio[1593]: info: CURURI: music-library/NAS/FLAC/Jazz/Pat Metheny
Apr 18 14:13:03 volumiopi volumio[1593]: info: Preload queue cleared
Apr 18 14:13:18 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 18 14:13:18 volumiopi volumio[1593]: info: CURURI: music-library/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984 Japan ECM Gold Collection 2003)
Apr 18 14:13:19 volumiopi volumio[1593]: info: Preload queue cleared
Apr 18 14:13:25 volumiopi volumio[1593]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 18 14:13:25 volumiopi volumio[1593]: info: CURURI: music-library/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984)
Apr 18 14:13:25 volumiopi volumio[1593]: info: Preload queue cleared
Apr 18 14:13:31 volumiopi volumio[1593]: info: Preload queue cleared
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreStateMachine::ClearQueue
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreStateMachine::stop
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 18 14:13:31 volumiopi volumio[1593]: info: CorePlayQueue::clearPlayQueue
Apr 18 14:13:31 volumiopi volumio[1593]: info: CorePlayQueue::saveQueue
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreCommandRouter::volumioPushQueue
Apr 18 14:13:31 volumiopi volumio[1593]: info: CoreStateMachine::addQueueItems
Apr 18 14:13:31 volumiopi volumio[1593]: info: CorePlayQueue::addQueueItems
Apr 18 14:13:31 volumiopi volumio[1593]: info: Preload queue cleared
Apr 18 14:13:31 volumiopi volumio[1593]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984 Japan ECM Gold Collection 2003)
Apr 18 14:13:31 volumiopi volumio[1593]: info: Exploding uri music-library/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984 Japan ECM Gold Collection 2003) in service mpd
Apr 18 14:13:32 volumiopi volumio[1593]: info: ALBUMART /albumart?cacheid=722&web=Pat%20Metheny/1984%20First%20Circle/extralarge&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FPat%20Metheny%2F%5BECM%201278%5D%20Pat%20Metheny%20Group%20-%20The%20First%20Circle%20(1984%20Japan%20ECM%20Gold%20Collection%202003)%2FPat%20Metheny%20-%201984%20First%20Circle.cue&metadata=false
Apr 18 14:13:32 volumiopi volumio[1593]: info: URI /mnt/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984 Japan ECM Gold Collection 2003)/Pat Metheny - 1984 First Circle.cue
Apr 18 14:13:32 volumiopi volumio[1593]: info: ALBUMART /albumart?cacheid=722&web=Pat%20Metheny/1984%20First%20Circle/extralarge&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FPat%20Metheny%2F%5BECM%201278%5D%20Pat%20Metheny%20Group%20-%20The%20First%20Circle%20(1984%20Japan%20ECM%20Gold%20Collection%202003)%2FPat%20Metheny%20-%201984%20First%20Circle.cue&metadata=false
Apr 18 14:13:32 volumiopi volumio[1593]: info: URI /mnt/NAS/FLAC/Jazz/Pat Metheny/[ECM 1278] Pat Metheny Group - The First Circle (1984 Japan ECM Gold Collection 2003)/Pat Metheny - 1984 First Circle.cue
Apr 18 14:13:32 volumiopi volumio[1593]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 18 14:13:32 volumiopi volumio[1593]: Error: Unable to resolve or reject the same promise twice
Apr 18 14:13:32 volumiopi volumio[1593]:     at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Apr 18 14:13:32 volumiopi volumio[1593]:     at /volumio/app/plugins/music_service/mpd/index.js:2568:21
Apr 18 14:13:32 volumiopi volumio[1593]:     at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Apr 18 14:13:32 volumiopi volumio[1593]:     at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Apr 18 14:13:32 volumiopi volumio[1593]:     at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Apr 18 14:13:32 volumiopi volumio[1593]:     at Socket.emit (events.js:315:20)
Apr 18 14:13:32 volumiopi volumio[1593]:     at addChunk (internal/streams/readable.js:309:12)
Apr 18 14:13:32 volumiopi volumio[1593]:     at readableAddChunk (internal/streams/readable.js:280:11)
Apr 18 14:13:32 volumiopi volumio[1593]:     at Socket.Readable.push (internal/streams/readable.js:223:10)
Apr 18 14:13:32 volumiopi volumio[1593]:     at Pipe.onStreamRead (internal/stream_base_commons.js:188:23)
Apr 18 14:13:32 volumiopi volumio[1593]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 18 14:13:34 volumiopi sudo[27748]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-18 14:12
Apr 18 14:13:34 volumiopi sudo[27748]: 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="56d8f4e08ca3cb143324aa0740c362c469043a9b"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="a77c7968e70cebb21b39a3791120006fed600769"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sun 31 Mar 2024 12:34:13 PM CEST"
VOLUMIO_VERSION="3.637"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="41a89107ca82d89cff75a3106ff77fdf"