Jun 02 10:44:06 volumiosub volumio[1714]: info: Preload queue cleared Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::ClearQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::stPlaybackTimer Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::updateTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 37 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::serviceStop Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 37 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::serviceStop Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::stop Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::clearPlayQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::saveQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::addQueueItems Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::addQueueItems Jun 02 10:44:06 volumiosub volumio[1714]: info: Preload queue cleared Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Angry Fist/1-04 My Sweet Dog.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Angry Fist/1-04 My Sweet Dog.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-01 TOUCH YOU.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-01 TOUCH YOU.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-02 ANOTHER STARTING LINE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-02 ANOTHER STARTING LINE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-03 NOTHING TO LOSE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-03 NOTHING TO LOSE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-04 RAIN FOREVER.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/ANOTHER STARTING LINE/1-04 RAIN FOREVER.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-01 MAXIMUM OVERDRIVE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-01 MAXIMUM OVERDRIVE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-02 LONELY.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-02 LONELY.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-03 SUMMER OF LOVE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-03 SUMMER OF LOVE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-04 SINCE YOU BEEN GONE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-04 SINCE YOU BEEN GONE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-05 WAIT FOR THE SUN.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-05 WAIT FOR THE SUN.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-06 TELL ME SOMETHING, HAPPY NEWS.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-06 TELL ME SOMETHING, HAPPY NEWS.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-07 GROOVY CREW.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-07 GROOVY CREW.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-08 SATURDAY NIGHT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-08 SATURDAY NIGHT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-09 CALIFORNIA DREAMIN’.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-09 CALIFORNIA DREAMIN’.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-10 SELFISH GIRL.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-10 SELFISH GIRL.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-11 I’M WALKIN’.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-11 I’M WALKIN’.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-12 NEW LIFE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-12 NEW LIFE.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-13 KISS ME AGAIN.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-13 KISS ME AGAIN.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-14 IN THE BRIGHTLY MOONLIGHT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-14 IN THE BRIGHTLY MOONLIGHT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-15 GROWING UP.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-15 GROWING UP.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-16 [Secret Track].flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP/1-16 [Secret Track].flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/10 SELFISH GIRL.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/10 SELFISH GIRL.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.591+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.592+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-07 NO HEROES.mp3" title="NO HEROES" Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::saveQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::updateTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPlay Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::play index 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::addQueueItems Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::addQueueItems Jun 02 10:44:06 volumiosub volumio[1714]: info: Preload queue cleared Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-01 WHO'LL BE THE NEXT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-01 WHO'LL BE THE NEXT.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-02 DIGGING UP THE GROUND.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-02 DIGGING UP THE GROUND.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-03 SUNNY DAY.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-03 SUNNY DAY.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-04 FALL.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-04 FALL.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-05 YOU AND ME.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-05 YOU AND ME.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-06 HEAD OVER HEELS.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/LAST OF SUNNY DAY/1-06 HEAD OVER HEELS.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Love Is A Battlefield/1-01 This Is Love.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Love Is A Battlefield/1-01 This Is Love.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Love Is A Battlefield/1-04 Can't Help Falling In Love.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/Love Is A Battlefield/1-04 Can't Help Falling In Love.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-01 TURNING BACK.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-01 TURNING BACK.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-02 STANDING STILL.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-02 STANDING STILL.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-03 TEENAGERS ARE ALL ASSHOLES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-03 TEENAGERS ARE ALL ASSHOLES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-04 JUST ROCK.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-04 JUST ROCK.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-05 DEAR MY FRIENDS.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-05 DEAR MY FRIENDS.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-06 STAY GOLD.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-06 STAY GOLD.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-07 NO HEROES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-07 NO HEROES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-08 GLORY.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-08 GLORY.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-09 PLEASE PLEASE PLEASE.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-09 PLEASE PLEASE PLEASE.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-10 GREEN ACRES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-10 GREEN ACRES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-11 CHANGES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-11 CHANGES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-12 MAKING THE ROAD BLUES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-12 MAKING THE ROAD BLUES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-13 TINKERBELL HATES GOATEES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-13 TINKERBELL HATES GOATEES.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-14 LIFT ME UP DON’T BRING ME DOWN.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-14 LIFT ME UP DON’T BRING ME DOWN.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-15 PENTAX.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-15 PENTAX.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-16 NOTHING.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-16 NOTHING.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-17 MOSH UNDER THE RAINBOW.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-17 MOSH UNDER THE RAINBOW.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-18 STARRY NIGHT.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-18 STARRY NIGHT.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-19 BRAND NEW SUNSET.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-19 BRAND NEW SUNSET.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-20 [Secret Track].mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/MAKING THE ROAD/1-20 [Secret Track].mp3 Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-01 All Generations.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-01 All Generations.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-02 The Gift.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-02 The Gift.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-03 Can I Be Kind To You.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-03 Can I Be Kind To You.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-04 Going Crazy.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-04 Going Crazy.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-05 Time To Crow.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-05 Time To Crow.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-06 My Girl.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-06 My Girl.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-07 Hello My Junior.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-07 Hello My Junior.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-08 Big Ol' Clock.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-08 Big Ol' Clock.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-09 We're All Grown Up.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-09 We're All Grown Up.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-10 Punk Rock Is The Answer.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-10 Punk Rock Is The Answer.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-11 Pacific Sun.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-11 Pacific Sun.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-12 I Know You Love Me.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-12 I Know You Love Me.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-13 Bridge Over Troubled Water.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-13 Bridge Over Troubled Water.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-14 Free.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-14 Free.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-15 Friend Song.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-15 Friend Song.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Adding Item to queue: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-16 Cabbage Surfin'.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: Using cached record of: music-library/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/The Gift/1-16 Cabbage Surfin'.flac Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::saveQueue Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::play index undefined Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::updateTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrackBlock Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::startPlaybackTimer Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::clearAddPlayTracks NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand stop took 96 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand stop took 24 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 13 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand clear took 13 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 11 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 11 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand add "NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: No code Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus stop Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: No code Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.697+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.697+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.697+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.698+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.698+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.699+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.700+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.702+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.703+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.703+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 44ms Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.705+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_STOPPED positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 44ms Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.707+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: error: updateQueue error: null Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 53ms Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand add "NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" took 52 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 18ms Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 18ms Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand play Jun 02 10:44:06 volumiosub volumio[1714]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 71ms Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces system playlist update Jun 02 10:44:06 volumiosub volumio[1714]: info: Ignoring MPD Status Update Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 27ms Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand play took 23 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 20ms Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 20ms Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 4 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: Jun 02 10:44:06 volumiosub volumio[1714]: ---------------------------- MPD announces state update: player Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::getState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand status Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 8 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 6 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 6 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 4 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus stop Jun 02 10:44:06 volumiosub volumio[1714]: info: ------------------------------ 19ms Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand status took 13 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseState Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus play Jun 02 10:44:06 volumiosub volumio[1714]: info: Received an update from plugin. extracting info from payload Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus play Jun 02 10:44:06 volumiosub volumio[1714]: info: Received an update from plugin. extracting info from payload Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus play Jun 02 10:44:06 volumiosub volumio[1714]: info: Received an update from plugin. extracting info from payload Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:06 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus play Jun 02 10:44:06 volumiosub volumio[1714]: info: Received an update from plugin. extracting info from payload Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:06 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:06 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.982+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.983+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=0 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.984+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.990+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.990+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.990+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.992+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.994+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.994+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.995+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:06 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.995+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.996+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.997+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.998+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:06.991+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:07.001+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio[1714]: info: ------------------------------ 101ms Jun 02 10:44:07 volumiosub volumio[1714]: info: ------------------------------ 102ms Jun 02 10:44:07 volumiosub volumio[1714]: info: ------------------------------ 103ms Jun 02 10:44:07 volumiosub volumio[1714]: info: ------------------------------ 101ms Jun 02 10:44:07 volumiosub volumio[1714]: info: sendMpdCommand playlistinfo took 86 milliseconds Jun 02 10:44:07 volumiosub volumio[1714]: verbose: ControllerMpd::parseTrackInfo Jun 02 10:44:07 volumiosub volumio[1714]: info: ControllerMpd::pushState Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreCommandRouter::servicePushState Jun 02 10:44:07 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:07 volumiosub volumio[1714]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":194,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"My First Kiss","artist":"Hi-STANDARD","album":"GROWING UP / Love is a Battlefield","uri":"NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3","trackType":"mp3"} Jun 02 10:44:07 volumiosub volumio[1714]: verbose: CURRENT POSITION 22 Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreStateMachine::syncState stateService play Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreStateMachine::syncState currentStatus play Jun 02 10:44:07 volumiosub volumio[1714]: info: Received an update from plugin. extracting info from payload Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:07 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreStateMachine::pushState Jun 02 10:44:07 volumiosub volumio[1714]: info: CorePlayQueue::getTrack 22 Jun 02 10:44:07 volumiosub volumio[1714]: info: CoreCommandRouter::volumioPushState Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:07.035+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:07.036+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" state=STATUS_PLAYING positionMs=871 volume=100 Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:07.036+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:07.036+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.146:35284,00:00:00:00:00:00%23 @ 0x2740030" id="mnt/NAS/raspberrypiNAS/10.Music/Hi-STANDARD/GROWING UP Love is a Battlefield/18 My First Kiss.mp3" title="My First Kiss" Jun 02 10:44:07 volumiosub volumio[1714]: info: ------------------------------ 130ms Jun 02 10:44:08 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:08.905+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:12 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:12.222+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:17 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:17.844+09:00 level=INFO msg="new address was allocated" component=ble/conn old=36 new=37 Jun 02 10:44:19 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:19.712+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:23 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:23.101+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:26 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:26.546+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:29 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:29.853+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:33 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:33.255+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:44:36 volumiosub volumio5-onboarding[2094]: time=2026-06-02T10:44:36.627+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=35 chunks=1 index=0 tries=11 Jun 02 10:45:26 volumiosub volumio[1714]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 10:45:26 volumiosub volumio[1714]: TypeError: Cannot read properties of null (reading 'slice') Jun 02 10:45:26 volumiosub volumio[1714]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jun 02 10:45:26 volumiosub volumio[1714]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jun 02 10:45:26 volumiosub volumio[1714]: at Object.onceWrapper (node:events:629:26) Jun 02 10:45:26 volumiosub volumio[1714]: at ClientRequest.emit (node:events:514:28) Jun 02 10:45:26 volumiosub volumio[1714]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jun 02 10:45:26 volumiosub volumio[1714]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jun 02 10:45:26 volumiosub volumio[1714]: at TLSSocket.socketOnData (node:_http_client:535:22) Jun 02 10:45:26 volumiosub volumio[1714]: at TLSSocket.emit (node:events:514:28) Jun 02 10:45:26 volumiosub volumio[1714]: at addChunk (node:internal/streams/readable:343:12) Jun 02 10:45:26 volumiosub volumio[1714]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 02 10:45:26 volumiosub volumio[1714]: at Readable.push (node:internal/streams/readable:253:10) Jun 02 10:45:26 volumiosub volumio[1714]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jun 02 10:45:26 volumiosub volumio[1714]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 10:45:27 volumiosub sudo[25963]: volumio : unable to resolve host volumiosub: System error Jun 02 10:45:27 volumiosub sudo[25963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 10:44' Jun 02 10:45:27 volumiosub sudo[25963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"