-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2024-05-13 17:09:56 CST. --
May 13 17:08:00 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPlay
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::play index 21
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::stop
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::stPlaybackTimer
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::updateTrackBlock
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrackBlock
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 7
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::serviceStop
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 7
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::serviceStop
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::stop
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand stop
May 13 17:08:01 volumio volumio[1515]: info:
May 13 17:08:01 volumio volumio[1515]: ---------------------------- MPD announces state update: player
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand stop took 89 milliseconds
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::getState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand status
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::play index undefined
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::startPlaybackTimer
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::clearAddPlayTracks USB/新加卷/无损CD/s.h.e - 不想长大.ape
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand stop
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand status took 5 milliseconds
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand stop took 2 milliseconds
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand clear
May 13 17:08:01 volumio volumio[1515]: info:
May 13 17:08:01 volumio volumio[1515]: ---------------------------- MPD announces system playlist update
May 13 17:08:01 volumio volumio[1515]: info: Ignoring MPD Status Update
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand playlistinfo took 4 milliseconds
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand clear took 3 milliseconds
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseTrackInfo
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand add "USB/新加卷/无损CD/s.h.e - 不想长大.ape"
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::pushState
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::servicePushState
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: 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":"喜欢你","artist":"BEYOND","album":"BEYOND珍藏版CD1","uri":"USB/新加卷/无损CD/beyond - 喜欢你.ape","trackType":"ape"}
May 13 17:08:01 volumio volumio[1515]: verbose: CURRENT POSITION 21
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState stateService stop
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState currentStatus stop
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: No code
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: ------------------------------ 49ms
May 13 17:08:01 volumio volumio[1515]: error: updateQueue error: null
May 13 17:08:01 volumio volumio[1515]: info:
May 13 17:08:01 volumio volumio[1515]: ---------------------------- MPD announces system playlist update
May 13 17:08:01 volumio volumio[1515]: info: Ignoring MPD Status Update
May 13 17:08:01 volumio volumio[1515]: info: ------------------------------ 43ms
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand add "USB/新加卷/无损CD/s.h.e - 不想长大.ape" took 40 milliseconds
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand play
May 13 17:08:01 volumio volumio[1515]: info: ------------------------------ 15ms
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand play took 13 milliseconds
May 13 17:08:01 volumio volumio[1515]: info:
May 13 17:08:01 volumio volumio[1515]: ---------------------------- MPD announces state update: player
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::getState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand status
May 13 17:08:01 volumio volumio[1515]: info:
May 13 17:08:01 volumio volumio[1515]: ---------------------------- MPD announces state update: player
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand status took 3 milliseconds
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::getState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand status
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand status took 3 milliseconds
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand playlistinfo took 3 milliseconds
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseState
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseTrackInfo
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::pushState
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::servicePushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"s.h.e - 不想长大.ape","artist":null,"album":null,"uri":"USB/新加卷/无损CD/s.h.e - 不想长大.ape","trackType":"ape"}
May 13 17:08:01 volumio volumio[1515]: verbose: CURRENT POSITION 21
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState stateService play
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState currentStatus stop
May 13 17:08:01 volumio volumio[1515]: info: ------------------------------ 16ms
May 13 17:08:01 volumio volumio[1515]: info: sendMpdCommand playlistinfo took 6 milliseconds
May 13 17:08:01 volumio volumio[1515]: verbose: ControllerMpd::parseTrackInfo
May 13 17:08:01 volumio volumio[1515]: info: ControllerMpd::pushState
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::servicePushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"s.h.e - 不想长大.ape","artist":null,"album":null,"uri":"USB/新加卷/无损CD/s.h.e - 不想长大.ape","trackType":"ape"}
May 13 17:08:01 volumio volumio[1515]: verbose: CURRENT POSITION 21
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState stateService play
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::syncState currentStatus play
May 13 17:08:01 volumio volumio[1515]: info: Received an update from plugin. extracting info from payload
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: CoreStateMachine::pushState
May 13 17:08:01 volumio volumio[1515]: info: CorePlayQueue::getTrack 21
May 13 17:08:01 volumio volumio[1515]: info: CoreCommandRouter::volumioPushState
May 13 17:08:01 volumio volumio[1515]: info: ------------------------------ 45ms
May 13 17:08:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 17:08:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 17:08:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 13 17:08:02 volumio volumio-remote-updater[527]: No test mode
May 13 17:08:02 volumio volumio-remote-updater[527]: No alpha test mode
May 13 17:08:02 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 13 17:08:03 volumio volumio[1515]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Fix for mpd crashing with wrongly formatted cue files
- Restore install-to-disk on x86 and ThinkerBoard
- Fix for Waveshare 11.9inch DSI Display touch panel on RPi
- Fix for missing metadata on CD playback
- Fix for metadata with Classic and Modern UI
- Fix for factory-reset from USB on Motivo
- Fix for Volumio logo on boot on Motivo
- Various fixes for TIDAL Connect
- Fix japanese and thai characters on HDMI display
- Fix for keyboard opening on select on mobile
NEW ADDITIONS
- Add filtering to QOBUZ New releases
- Bump MPD to 0.23.15-3
- Add support for Waveshare 4.0inch DSI Display on RPi
- Improvements for reducing boot time
- Add patched driver for ax88179 on RPi
","title":"Update v3.661","updateavailable":true}
May 13 17:08:03 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::volumioGetBrowseSources
May 13 17:08:04 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 17:08:05 volumio volumio[1515]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 13 17:08:05 volumio volumio[1515]: info: Completed starting MyVolumio Plugin
May 13 17:08:05 volumio volumio[1515]: info: BOOT COMPLETED
May 13 17:08:05 volumio volumio[1515]: [Metrics] CommandRouter: 44s 366.62ms
May 13 17:08:05 volumio volumio[1515]: info: CoreCommandRouter::volumiosetStartupVolume
May 13 17:08:05 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 17:08:05 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 17:08:05 volumio volumio[1515]: info: CoreCommandRouter::Close All Modals sent
May 13 17:08:05 volumio volumio[1515]: info: CoreCommandRouter::Close All Modals sent
May 13 17:08:05 volumio volumio[1515]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
May 13 17:08:05 volumio volumio[1515]: aplay: main:828: audio open error: Device or resource busy
May 13 17:08:06 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:06 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 13 17:08:06 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 17:08:06 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 13 17:08:11 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:17 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:23 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:29 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:29 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 17:08:29 volumio volumio[1515]: info: CURURI: music-library
May 13 17:08:29 volumio volumio[1515]: info: Preload queue cleared
May 13 17:08:31 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 17:08:31 volumio volumio[1515]: info: CURURI: music-library/USB
May 13 17:08:31 volumio volumio[1515]: info: Preload queue cleared
May 13 17:08:32 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 17:08:32 volumio volumio[1515]: info: CURURI: music-library/USB/新加卷
May 13 17:08:32 volumio volumio[1515]: info: Preload queue cleared
May 13 17:08:34 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:38 volumio volumio[1515]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
May 13 17:08:38 volumio volumio[1515]: info: CURURI: music-library/USB/新加卷/MQ
May 13 17:08:38 volumio volumio[1515]: info: Preload queue cleared
May 13 17:08:40 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:46 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:52 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:08:57 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:03 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:09 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:15 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:20 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:26 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:32 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:38 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:43 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:49 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:55 volumio wpa_supplicant[795]: wlan0: Failed to initiate sched scan
May 13 17:09:55 volumio volumio[1515]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 17:09:55 volumio volumio[1515]: Error: connect ETIMEDOUT 168.143.171.189:443
May 13 17:09:55 volumio volumio[1515]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
May 13 17:09:55 volumio volumio[1515]: errno: -110,
May 13 17:09:55 volumio volumio[1515]: code: 'ETIMEDOUT',
May 13 17:09:55 volumio volumio[1515]: syscall: 'connect',
May 13 17:09:55 volumio volumio[1515]: address: '168.143.171.189',
May 13 17:09:55 volumio volumio[1515]: port: 443
May 13 17:09:55 volumio volumio[1515]: }
May 13 17:09:55 volumio volumio[1515]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 17:09:56 volumio sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 17:08
May 13 17:09:56 volumio sudo[1779]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST"
VOLUMIO_VERSION="3.569"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"