-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Wed 2024-04-03 00:53:40 UTC. --
Apr 03 00:52:00 volumio1 volumio[2629]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 03 00:52:00 volumio1 volumio[2629]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 03 00:52:00 volumio1 volumio[2629]: info: Streaming services startup
Apr 03 00:52:00 volumio1 volumio[2629]: info: Starting Streaming Daemon
Apr 03 00:52:00 volumio1 sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 03 00:52:00 volumio1 volumio[2629]: verbose: New Socket.io Connection to 192.168.11.32 from 192.168.11.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.3.1 Safari/605.1.15 Total Clients: 7
Apr 03 00:52:00 volumio1 sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 03 00:52:00 volumio1 systemd[1]: Stopping Volumio Streaming Daemon...
Apr 03 00:52:00 volumio1 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM
Apr 03 00:52:00 volumio1 systemd[1]: volumio-streaming-daemon.service: Succeeded.
Apr 03 00:52:00 volumio1 systemd[1]: Stopped Volumio Streaming Daemon.
Apr 03 00:52:00 volumio1 systemd[1]: Started Volumio Streaming Daemon.
Apr 03 00:52:00 volumio1 sudo[2834]: pam_unix(sudo:session): session closed for user root
Apr 03 00:52:00 volumio1 volumio-streaming-daemon[2840]: ############################
Apr 03 00:52:00 volumio1 volumio-streaming-daemon[2840]: # Volumio Streaming Daemon #
Apr 03 00:52:00 volumio1 volumio-streaming-daemon[2840]: # Running on port 7777 #
Apr 03 00:52:00 volumio1 volumio-streaming-daemon[2840]: ############################
Apr 03 00:52:00 volumio1 volumio-streaming-daemon[2840]: Environment: production
Apr 03 00:52:02 volumio1 volumio[2629]: verbose: New Socket.io Connection to 192.168.11.32 from 192.168.11.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.3.1 Safari/605.1.15 Total Clients: 8
Apr 03 00:52:03 volumio1 volumio-streaming-daemon[2840]: Environment: production
Apr 03 00:52:03 volumio1 volumio[2629]: info: Fetching Streaming Services browse cache
Apr 03 00:52:04 volumio1 volumio[2629]: verbose: New Socket.io Connection to 192.168.11.32 from 192.168.11.14 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.3.1 Safari/605.1.15 Total Clients: 9
Apr 03 00:52:04 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetState
Apr 03 00:52:04 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:04 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetQueue
Apr 03 00:52:04 volumio1 volumio[2629]: info: CoreStateMachine::getQueue
Apr 03 00:52:05 volumio1 volumio[2629]: info: CorePlayQueue::getQueue
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetState
Apr 03 00:52:05 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetQueue
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreStateMachine::getQueue
Apr 03 00:52:05 volumio1 volumio[2629]: info: CorePlayQueue::getQueue
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 03 00:52:05 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 03 00:52:06 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%91%89%E5%8A%A0%E7%80%AC%E5%A4%AA%E9%83%8E/image/940615c6-0cc5-43a0-895f-d35b80e4890c.jpg'
Apr 03 00:52:06 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yo-Yo%20Ma/image/67a33784-6c3e-4960-babd-df83b2ebdf19.jpg'
Apr 03 00:52:07 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%BE%BD%E6%AF%9B%E7%94%B0%E4%B8%88%E5%8F%B2/image/4bf958b3-8bcf-4a4a-b1e3-6b5f1067c7e1.jpg'
Apr 03 00:52:07 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GONTITI/image/490e9bc8-fa04-4e38-bc6b-0b3b3aa74125.jpg'
Apr 03 00:52:07 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/James%20Horner/image/67cd5901-32ba-4e50-9569-ef9ff8daa8a1.jpg'
Apr 03 00:52:11 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:12 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:12 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 03 00:52:12 volumio1 volumio[2629]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 03 00:52:12 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 03 00:52:12 volumio1 volumio[2629]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio
Apr 03 00:52:12 volumio1 volumio[2629]: info: Received Get System Version
Apr 03 00:52:12 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 03 00:52:15 volumio1 volumio[2629]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Wes/image%202/fae42d8e-cb27-480b-87bf-6ae9136a4a1b.jpg'
Apr 03 00:52:18 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:18 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 03 00:52:18 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 03 00:52:19 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:20 volumio1 volumio[2629]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 03 00:52:20 volumio1 volumio[2629]: info: Completed starting MyVolumio Plugin
Apr 03 00:52:20 volumio1 volumio[2629]: info: BOOT COMPLETED
Apr 03 00:52:20 volumio1 volumio[2629]: [Metrics] CommandRouter: 46s 361.40ms
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 03 00:52:20 volumio1 volumio[2629]: info: VolumeController:: Setting startup Volume 85
Apr 03 00:52:20 volumio1 volumio[2629]: info: VolumeController::SetAlsaVolume85
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::Close All Modals sent
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::Close All Modals sent
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:20 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:20 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:21 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 03 00:52:21 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 03 00:52:21 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 03 00:52:31 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 03 00:52:31 volumio1 volumio[2629]: info: Listing playlists
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPlay
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: UNSET VOLATILE
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::play index undefined
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::startPlaybackTimer
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::clearAddPlayTracks USB/HD_myusic/Classic/image/01 霧の浅瀬.aif
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand stop
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand stop took 6 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand clear
Apr 03 00:52:34 volumio1 volumio[2629]: info:
Apr 03 00:52:34 volumio1 volumio[2629]: ---------------------------- MPD announces system playlist update
Apr 03 00:52:34 volumio1 volumio[2629]: info: Ignoring MPD Status Update
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand clear took 8 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand add "USB/HD_myusic/Classic/image/01 霧の浅瀬.aif"
Apr 03 00:52:34 volumio1 volumio[2629]: error: updateQueue error: null
Apr 03 00:52:34 volumio1 volumio[2629]: info:
Apr 03 00:52:34 volumio1 volumio[2629]: ---------------------------- MPD announces system playlist update
Apr 03 00:52:34 volumio1 volumio[2629]: info: Ignoring MPD Status Update
Apr 03 00:52:34 volumio1 volumio[2629]: info: ------------------------------ 15ms
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand add "USB/HD_myusic/Classic/image/01 霧の浅瀬.aif" took 9 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand play
Apr 03 00:52:34 volumio1 volumio[2629]: info: ------------------------------ 10ms
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand play took 7 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: info:
Apr 03 00:52:34 volumio1 volumio[2629]: ---------------------------- MPD announces state update: player
Apr 03 00:52:34 volumio1 volumio[2629]: info: ControllerMpd::getState
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand status
Apr 03 00:52:34 volumio1 volumio[2629]: info:
Apr 03 00:52:34 volumio1 volumio[2629]: ---------------------------- MPD announces state update: player
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand status took 80 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: info: ControllerMpd::getState
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand status
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::parseState
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand status took 15 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::parseState
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::parseTrackInfo
Apr 03 00:52:34 volumio1 volumio[2629]: info: ControllerMpd::pushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::servicePushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"霧の浅瀬","artist":"Karl Jenkins","album":"image","uri":"USB/HD_myusic/Classic/image/01 霧の浅瀬.aif","trackType":"aif"}
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: CURRENT POSITION 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::syncState stateService play
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::syncState currentStatus stop
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: ------------------------------ 184ms
Apr 03 00:52:34 volumio1 volumio[2629]: info: sendMpdCommand playlistinfo took 65 milliseconds
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: ControllerMpd::parseTrackInfo
Apr 03 00:52:34 volumio1 volumio[2629]: info: ControllerMpd::pushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::servicePushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"霧の浅瀬","artist":"Karl Jenkins","album":"image","uri":"USB/HD_myusic/Classic/image/01 霧の浅瀬.aif","trackType":"aif"}
Apr 03 00:52:34 volumio1 volumio[2629]: verbose: CURRENT POSITION 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::syncState stateService play
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::syncState currentStatus play
Apr 03 00:52:34 volumio1 volumio[2629]: info: Received an update from plugin. extracting info from payload
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:34 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:34 volumio1 volumio[2629]: info: ------------------------------ 161ms
Apr 03 00:52:35 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:36 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:37 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPlay
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: UNSET VOLATILE
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::play index 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::stop
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: UNSET VOLATILE
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::stPlaybackTimer
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::updateTrackBlock
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrackBlock
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::serviceStop
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 0
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::serviceStop
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::stop
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand stop
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand stop took 87 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::play index undefined
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::startPlaybackTimer
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::clearAddPlayTracks USB/HD_myusic/Classic/image/16 情熱大陸.aif
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand stop
Apr 03 00:52:55 volumio1 volumio[2629]: info:
Apr 03 00:52:55 volumio1 volumio[2629]: ---------------------------- MPD announces state update: player
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::getState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand status
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand stop took 5 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand clear
Apr 03 00:52:55 volumio1 volumio[2629]: info:
Apr 03 00:52:55 volumio1 volumio[2629]: ---------------------------- MPD announces system playlist update
Apr 03 00:52:55 volumio1 volumio[2629]: info: Ignoring MPD Status Update
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand status took 7 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand clear took 6 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand add "USB/HD_myusic/Classic/image/16 情熱大陸.aif"
Apr 03 00:52:55 volumio1 volumio[2629]: error: updateQueue error: null
Apr 03 00:52:55 volumio1 volumio[2629]: info:
Apr 03 00:52:55 volumio1 volumio[2629]: ---------------------------- MPD announces system playlist update
Apr 03 00:52:55 volumio1 volumio[2629]: info: Ignoring MPD Status Update
Apr 03 00:52:55 volumio1 volumio[2629]: info: ------------------------------ 13ms
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand playlistinfo took 13 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand add "USB/HD_myusic/Classic/image/16 情熱大陸.aif" took 13 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseTrackInfo
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand play
Apr 03 00:52:55 volumio1 volumio[2629]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 03 00:52:55 volumio1 volumio[2629]: info: ------------------------------ 35ms
Apr 03 00:52:55 volumio1 volumio[2629]: info: ------------------------------ 19ms
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand play took 7 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info:
Apr 03 00:52:55 volumio1 volumio[2629]: ---------------------------- MPD announces state update: player
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::getState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand status
Apr 03 00:52:55 volumio1 volumio[2629]: info:
Apr 03 00:52:55 volumio1 volumio[2629]: ---------------------------- MPD announces state update: player
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand status took 45 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::getState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand status
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand status took 7 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand playlistinfo took 5 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseState
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseTrackInfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::servicePushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情熱大陸","artist":"葉加瀬太郎 with 小松亮太","album":"image","uri":"USB/HD_myusic/Classic/image/16 情熱大陸.aif","trackType":"aif"}
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: CURRENT POSITION 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::syncState stateService play
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::syncState currentStatus stop
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: ------------------------------ 99ms
Apr 03 00:52:55 volumio1 volumio[2629]: info: sendMpdCommand playlistinfo took 40 milliseconds
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: ControllerMpd::parseTrackInfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: ControllerMpd::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::servicePushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"情熱大陸","artist":"葉加瀬太郎 with 小松亮太","album":"image","uri":"USB/HD_myusic/Classic/image/16 情熱大陸.aif","trackType":"aif"}
Apr 03 00:52:55 volumio1 volumio[2629]: verbose: CURRENT POSITION 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::syncState stateService play
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::syncState currentStatus play
Apr 03 00:52:55 volumio1 volumio[2629]: info: Received an update from plugin. extracting info from payload
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreStateMachine::pushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: CorePlayQueue::getTrack 15
Apr 03 00:52:55 volumio1 volumio[2629]: info: CoreCommandRouter::volumioPushState
Apr 03 00:52:55 volumio1 volumio[2629]: info: ------------------------------ 108ms
Apr 03 00:53:20 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:53:25 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:53:39 volumio1 volumio[2629]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 03 00:53:39 volumio1 volumio[2629]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net
Apr 03 00:53:39 volumio1 volumio[2629]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) {
Apr 03 00:53:39 volumio1 volumio[2629]: errno: -3007,
Apr 03 00:53:39 volumio1 volumio[2629]: code: 'ENOTFOUND',
Apr 03 00:53:39 volumio1 volumio[2629]: syscall: 'getaddrinfo',
Apr 03 00:53:39 volumio1 volumio[2629]: hostname: 'lastfm.freetls.fastly.net'
Apr 03 00:53:39 volumio1 volumio[2629]: }
Apr 03 00:53:39 volumio1 volumio[2629]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 03 00:53:39 volumio1 wpa_supplicant[639]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 03 00:53:40 volumio1 sudo[2881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 00:52
Apr 03 00:53:40 volumio1 sudo[2881]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b"
VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9"
VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1"
VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST"
VOLUMIO_VERSION="3.251"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"