-- 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"