-- Logs begin at Wed 2026-03-25 10:58:52 KST, end at Wed 2026-03-25 14:31:15 KST. -- Mar 25 14:30:01 adv-volumio volumio[29555]: info: MyVolumio token set successfully Mar 25 14:30:01 adv-volumio volumio[29555]: info: MYVOLUMIO: Adding device Mar 25 14:30:01 adv-volumio volumio[29555]: info: MYVOLUMIO: Evaluating Server Mar 25 14:30:02 adv-volumio volumio[29555]: info: MyVolumio status changed Mar 25 14:30:02 adv-volumio volumio[29555]: info: Streaming services startup Mar 25 14:30:02 adv-volumio volumio[29555]: info: Starting Streaming Daemon Mar 25 14:30:02 adv-volumio volumio[29555]: info: Removing browser output: myVolumio user plan is not superstar Mar 25 14:30:02 adv-volumio volumio[29555]: info: Removing audio output: Mar 25 14:30:02 adv-volumio volumio[29555]: info: Stoppping Tunnel 1 Mar 25 14:30:02 adv-volumio sudo[29893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 25 14:30:02 adv-volumio sudo[29893]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 14:30:02 adv-volumio sudo[29893]: pam_unix(sudo:session): session closed for user root Mar 25 14:30:02 adv-volumio sudo[29896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 25 14:30:02 adv-volumio sudo[29896]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 14:30:02 adv-volumio sudo[29896]: pam_unix(sudo:session): session closed for user root Mar 25 14:30:04 adv-volumio volumio[29555]: info: Setting Geolocation for MyVolumio to as1 Mar 25 14:30:04 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:04 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:04 adv-volumio volumio[29555]: info: Remote SSH Stopped Mar 25 14:30:04 adv-volumio volumio[29555]: error: Cannot start Volumio Streaming Daemon Mar 25 14:30:04 adv-volumio volumio[29555]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 25 14:30:04 adv-volumio volumio[29555]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 25 14:30:05 adv-volumio volumio[29555]: info: Updating MyVolumio device info Mar 25 14:30:05 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:05 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:06 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 14:30:07 adv-volumio volumio[29555]: info: Successfully Added MyVolumio device Mar 25 14:30:08 adv-volumio volumio[29555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 25 14:30:08 adv-volumio sudo[29830]: pam_unix(sudo:session): session closed for user root Mar 25 14:30:08 adv-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 14:30:08 adv-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Mar 25 14:30:08 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 14:30:08 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:08 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 14:30:09 adv-volumio volumio[29555]: info: Successfully Updated MyVolumio device Mar 25 14:30:09 adv-volumio volumio[29555]: info: Cannot mount NAS WZYDisk at system boot, trial number 3 ,retrying in 5 seconds Mar 25 14:30:09 adv-volumio volumio-remote-updater[4285]: No test mode Mar 25 14:30:09 adv-volumio volumio-remote-updater[4285]: No alpha test mode Mar 25 14:30:09 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 25 14:30:10 adv-volumio volumio[29555]: info: CoreCommandRouter::volumioGetState Mar 25 14:30:10 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 0 Mar 25 14:30:11 adv-volumio volumio[29555]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 25 14:30:11 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::volumioGetBrowseSources Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 14:30:12 adv-volumio volumio[29555]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 25 14:30:12 adv-volumio volumio[29555]: info: Completed starting MyVolumio Plugin Mar 25 14:30:12 adv-volumio volumio[29555]: [Metrics] CommandRouter: 46s 888.90ms Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::volumiosetStartupVolume Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::Close All Modals sent Mar 25 14:30:12 adv-volumio volumio[29555]: info: CoreCommandRouter::Close All Modals sent Mar 25 14:30:14 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 25 14:30:14 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 14:30:14 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 25 14:30:15 adv-volumio sudo[29921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carrot,password=Ilo2dul222,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.68/Music /mnt/NAS/WZYDisk Mar 25 14:30:15 adv-volumio sudo[29921]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 14:30:15 adv-volumio kernel: CIFS: Attempting to mount //192.168.10.68/Music Mar 25 14:30:20 adv-volumio volumio[29555]: info: BOOT COMPLETED Mar 25 14:30:25 adv-volumio sudo[29921]: pam_unix(sudo:session): session closed for user root Mar 25 14:30:25 adv-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 14:30:25 adv-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Mar 25 14:30:30 adv-volumio volumio[29555]: info: Cannot mount NAS WZYDisk at system boot, trial number 4 ,retrying in 5 seconds Mar 25 14:30:35 adv-volumio sudo[29958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carrot,password=Ilo2dul222,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.68/Music /mnt/NAS/WZYDisk Mar 25 14:30:35 adv-volumio sudo[29958]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 25 14:30:35 adv-volumio kernel: CIFS: Attempting to mount //192.168.10.68/Music Mar 25 14:30:46 adv-volumio sudo[29958]: pam_unix(sudo:session): session closed for user root Mar 25 14:30:46 adv-volumio volumio[29555]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 25 14:30:46 adv-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Mar 25 14:30:46 adv-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreCommandRouter::volumioPlay Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::play index 3 Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::stop Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::play index undefined Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 14:31:04 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:04 adv-volumio volumio[29555]: info: CoreStateMachine::startPlaybackTimer Mar 25 14:31:04 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:04 adv-volumio volumio[29555]: info: [DarkAscendants] clearAddPlayTrack quality=HI_RES_LOSSLESS useHiResProxy=true raw=true Mar 25 14:31:04 adv-volumio volumio[29555]: info: [DarkAscendants] clearAddPlayTrack: darkascendants/track/5761228 Mar 25 14:31:05 adv-volumio volumio[29555]: info: [TidalApi] getStreamUrl[HI_RES_LOSSLESS] trackId=5761228 audioQuality=HI_RES_LOSSLESS mimeType=application/dash+xml encType=NONE api.bitDepth=24 api.sampleRate=48000 Mar 25 14:31:05 adv-volumio volumio[29555]: info: [TidalApi] getStreamUrl DASH: quality=HI_RES_LOSSLESS codec=flac bitDepth=24 sampleRate=48000 segments=88 initUrl=AeIT61CMZ0g__&Key-Pair-Id=K14LZCZ9QUI4JL Mar 25 14:31:05 adv-volumio volumio[29555]: info: [TidalApi] getStreamUrl HiRes proxy registered: http://127.0.0.1:18321/hires/e1559cfd17964f93ba20714b Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/5761228","name":"Liberty","title":"Liberty","artist":"Anette Askvik","album":"Liberty (original edition)","albumart":"https://resources.tidal.com/images/434e78e0/84d9/411f/8c79/4ccc6e742600/1280x1280.jpg","duration":345,"samplerate":"48 kHz","bitdepth":"24 bit","trackType":"flac","channels":2,"seek":0} Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: CURRENT POSITION 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::syncState stateService play Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::syncState currentStatus stop Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] listening on 127.0.0.1:18321 Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand stop took 7 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand clear took 2 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:18321/hires/e1559cfd17964f93ba20714b" Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces system playlist update Mar 25 14:31:05 adv-volumio volumio[29555]: info: Ignoring MPD Status Update Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand add "http://127.0.0.1:18321/hires/e1559cfd17964f93ba20714b" took 16 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 14ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 11ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 10ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 8ms Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand play Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 12ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 11ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand play took 6 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: STATE SERVICE {"status":"play","service":"dark_ascendants","uri":"darkascendants/track/5761228","name":"Liberty","title":"Liberty","artist":"Anette Askvik","album":"Liberty (original edition)","albumart":"https://resources.tidal.com/images/434e78e0/84d9/411f/8c79/4ccc6e742600/1280x1280.jpg","duration":345,"samplerate":"48 kHz","bitdepth":"24 bit","trackType":"flac","channels":2,"seek":0} Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: CURRENT POSITION 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::syncState stateService play Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::syncState currentStatus play Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received an update from plugin. extracting info from payload Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::volumioPushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreStateMachine::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::volumioPushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] fetching init segment: C8K0BxtBx2GwPMfUzT-cAeIT61CMZ0g__&Key-Pair-Id=K14LZCZ9QUI4JL Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] init segment size=619 Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] STREAMINFO OK Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] streaming from seg 1/88 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: [HiResProxy] seg 1/88 done Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 84 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 84 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 83 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: Mar 25 14:31:05 adv-volumio volumio[29555]: ---------------------------- MPD announces state update: player Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::getState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 18 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 10 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand status took 6 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseState Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 124ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 122ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 121ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: verbose: ControllerMpd::parseTrackInfo Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ControllerMpd::pushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CoreCommandRouter::servicePushState Mar 25 14:31:05 adv-volumio volumio[29555]: info: CorePlayQueue::getTrack 3 Mar 25 14:31:05 adv-volumio volumio[29555]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current dark_ascendants Received mpd Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 51ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 38ms Mar 25 14:31:05 adv-volumio volumio[29555]: info: ------------------------------ 35ms Mar 25 14:31:15 adv-volumio volumio[29555]: verbose: ControllerMpd::sendMpdCommand status Mar 25 14:31:15 adv-volumio volumio[29555]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 14:31:15 adv-volumio volumio[29555]: TypeError: self.mpdPlugin.sendMpdCommand(...).then(...).catch is not a function Mar 25 14:31:15 adv-volumio volumio[29555]: at Timeout._onTimeout (/data/plugins/music_service/dark_ascendants/index.js:334:17) Mar 25 14:31:15 adv-volumio volumio[29555]: at listOnTimeout (internal/timers.js:554:17) Mar 25 14:31:15 adv-volumio volumio[29555]: at processTimers (internal/timers.js:497:7) Mar 25 14:31:15 adv-volumio volumio[29555]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 14:31:15 adv-volumio sudo[30071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-25 14:30 Mar 25 14:31:15 adv-volumio sudo[30071]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"