-- Logs begin at Sun 2026-03-22 03:32:35 KST, end at Sun 2026-08-30 11:00:38 KST. --
Aug 30 10:59:00 hswhdx volumio[776]: info: browseTIDALUri took 881 milliseconds
Aug 30 10:59:00 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357314
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357315
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357316
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357317
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357318
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357319
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357320
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357321
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357322
Aug 30 10:59:00 hswhdx volumio[776]: info: Preloading song: tidal://song/494357323
Aug 30 10:59:00 hswhdx volumio[776]: info: Exploding uri tidal://song/494357314 in service tidal
Aug 30 10:59:00 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: Exploding uri tidal://song/494357315 in service tidal
Aug 30 10:59:00 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:00 hswhdx volumio[776]: info: Exploding uri tidal://song/494357316 in service tidal
Aug 30 10:59:00 hswhdx volumio[776]: info: Exploding uri tidal://song/494357317 in service tidal
Aug 30 10:59:00 hswhdx volumio[776]: info: Exploding uri tidal://song/494357318 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: Exploding uri tidal://song/494357319 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: Exploding uri tidal://song/494357320 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: Exploding uri tidal://song/494357321 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: Exploding uri tidal://song/494357322 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: Exploding uri tidal://song/494357323 in service tidal
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 590 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 761 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 659 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 765 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 847 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 641 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 740 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 666 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 649 milliseconds
Aug 30 10:59:01 hswhdx volumio[776]: info: explodeTIDALUri took 750 milliseconds
Aug 30 10:59:09 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:09 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:09 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:09 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:44 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::ClearQueue
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::stop
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::stPlaybackTimer
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::serviceStop
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::serviceStop
Aug 30 10:59:44 hswhdx volumio[776]: info: [1788055184114] ControllerWebradio::stop
Aug 30 10:59:44 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand stop
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::clearPlayQueue
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::addQueueItems
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::addQueueItems
Aug 30 10:59:44 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/347013568
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/347013568 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4443915
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4443915 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4413087
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4413087 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4413086
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4413086 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4413085
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4413085 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/9376825
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/9376825 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4413083
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4413083 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/462237013
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/462237013 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/9376827
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/9376827 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4413084
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4413084 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/9376826
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/9376826 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/21978859
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/21978859 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/31861169
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/31861169 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/24979623
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/24979623 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/273663518
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/273663518 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/259781065
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/259781065 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/31861170
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/31861170 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/259781067
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/259781067 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/259781066
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/259781066 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/31861171
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/31861171 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/389707175
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/389707175 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/273663519
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/273663519 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/273663516
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/273663516 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/8753046
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/8753046 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/17603023
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/17603023 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/339530133
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/339530133 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/339530134
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/339530134 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/82623939
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/82623939 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4438889
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/4438889 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/339530135
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/339530135 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/421292081
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/421292081 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/421292080
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/421292080 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/421292079
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/421292079 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/50413562
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/50413562 in service tidal
Aug 30 10:59:44 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899179
Aug 30 10:59:44 hswhdx volumio[776]: info: Exploding uri tidal://song/239899179 in service tidal
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.149+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_STOPPED positionMs=0 volume=100
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.149+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=http://serpent0.duckdns.org:8088/kbsfm.pls title="kbs classic"
Aug 30 10:59:44 hswhdx volumio[776]: info:
Aug 30 10:59:44 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 10:59:44 hswhdx volumio[776]: info: sendMpdCommand stop took 312 milliseconds
Aug 30 10:59:44 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 10:59:44 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 10:59:44 hswhdx volumio[776]: info: sendMpdCommand status took 10 milliseconds
Aug 30 10:59:44 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 10:59:44 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 10:59:44 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 2 milliseconds
Aug 30 10:59:44 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 10:59:44 hswhdx volumio[776]: info: ControllerMpd::pushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::servicePushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3ODgyMjY3MTF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=YicVamWVQ~THmxR-OHxYDibdNkdQGitUgOl-6DmQw4zr6xvgQAoY7S-SPUjMY1fo6ne2~QTgJVM6x6I1Uj27Lp7NbOA9~BdJkqCsDq9cy-QLxn0fAAgUz35j5gOB7OSCK9JObn4y7~H2DYSDvheA~kLpBr0Jr7uIdI3CYmdUdrypn0HOKzM4q797MOEjc0leUfjLvw53gKYkUySPz0d5DrvDT84rFgNIgzZmFYGhODf8Y6XawsnSS9~wDTIDBP-vQSNJWslHYw04-JGTGMZF99ksXaXeW7HLu2exaZs-dzXj11F7~XDiUwJByRd9xEFEUps5P5IxcL9Tnx0fUvo0vw__","trackType":""}
Aug 30 10:59:44 hswhdx volumio[776]: verbose: CURRENT POSITION 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::syncState stateService stop
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::syncState currentStatus stop
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:44 hswhdx volumio[776]: info: No code
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:44 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 10:59:44 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.486+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_STOPPED positionMs=0 volume=100
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.486+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id= title=
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.488+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_STOPPED positionMs=0 volume=100
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.489+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id= title=
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.490+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_STOPPED positionMs=0 volume=100
Aug 30 10:59:44 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:44.491+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id= title=
Aug 30 10:59:44 hswhdx volumio[776]: info: ------------------------------ 76ms
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2179 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2189 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2183 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2180 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2186 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2199 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2200 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2215 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2197 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2203 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2229 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2185 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2233 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2195 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2184 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2206 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2199 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2236 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2194 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2247 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2212 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2214 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2205 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2231 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2230 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2221 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2224 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2260 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2281 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2355 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2403 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2387 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2436 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2479 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: explodeTIDALUri took 2536 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 10:59:46 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 10:59:46 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreCommandRouter::volumioPlay
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::play index 34
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::addQueueItems
Aug 30 10:59:46 hswhdx volumio[776]: info: CorePlayQueue::addQueueItems
Aug 30 10:59:46 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/434874763
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/434874763 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899177
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/239899177 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/17603024
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/17603024 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/17603022
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/17603022 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4443507
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/4443507 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/4443505
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/4443505 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899178
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/239899178 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/24979625
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/24979625 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/24979626
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/24979626 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/422379590
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/422379590 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/285192649
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/285192649 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/24021163
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/24021163 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/18099544
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/18099544 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/285192644
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/285192644 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/285192650
Aug 30 10:59:46 hswhdx volumio[776]: info: Exploding uri tidal://song/285192650 in service tidal
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::stop
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::play index undefined
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 10:59:46 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::startPlaybackTimer
Aug 30 10:59:46 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 30 10:59:46 hswhdx volumio[776]: info: [1788055186827] ControllerTidal::clearAddPlayTrack
Aug 30 10:59:46 hswhdx volumio[776]: info: Getting stream with soundQuality HI_RES
Aug 30 10:59:46 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand stop
Aug 30 10:59:46 hswhdx volumio[776]: info: sendMpdCommand stop took 91 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand clear
Aug 30 10:59:46 hswhdx volumio[776]: info:
Aug 30 10:59:46 hswhdx volumio[776]: ---------------------------- MPD announces system playlist update
Aug 30 10:59:46 hswhdx volumio[776]: info: Ignoring MPD Status Update
Aug 30 10:59:46 hswhdx volumio[776]: info: sendMpdCommand clear took 3 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/239899179&soundQuality=HI_RES"
Aug 30 10:59:46 hswhdx volumio[776]: error: updateQueue error: null
Aug 30 10:59:46 hswhdx volumio[776]: info:
Aug 30 10:59:46 hswhdx volumio[776]: ---------------------------- MPD announces system playlist update
Aug 30 10:59:46 hswhdx volumio[776]: info: Ignoring MPD Status Update
Aug 30 10:59:46 hswhdx volumio[776]: info: ------------------------------ 8ms
Aug 30 10:59:46 hswhdx volumio[776]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/239899179&soundQuality=HI_RES" took 5 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 30 10:59:46 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand play
Aug 30 10:59:46 hswhdx volumio[776]: info: ------------------------------ 6ms
Aug 30 10:59:46 hswhdx volumio[776]: info: sendMpdCommand play took 4 milliseconds
Aug 30 10:59:46 hswhdx volumio[776]: STREAMING PROXY: Handling url /?data=tidal://song/239899179&soundQuality=HI_RES
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 914 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 923 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 932 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 941 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 936 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: Executing endpoint getStreamUrltidal
Aug 30 10:59:47 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 971 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 969 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 1005 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 1080 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 1116 milliseconds
Aug 30 10:59:47 hswhdx volumio[776]: info: explodeTIDALUri took 1186 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: explodeTIDALUri took 1231 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: explodeTIDALUri took 1227 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: explodeTIDALUri took 1302 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: explodeTIDALUri took 1324 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 10:59:48 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 10:59:48 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 10:59:48 hswhdx volumio[776]: info: getStreamUrl took 752 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicyMjYxNTI3YjJjYzQyOGIyZjU2MWVkMDQwOTEwYTUwM182MS5tcDQ/0.flac?token=1788058788~ZGRkZjQwZTk5ZWYwZDU1OGRmYzI0OTNlMmE5ZTFmYTU3YWRkZmFhYg==
Aug 30 10:59:48 hswhdx volumio[776]: STREAMING PROXY: Requesting upstream with range: none
Aug 30 10:59:48 hswhdx volumio[776]: STREAMING PROXY: Upstream status code: 200
Aug 30 10:59:48 hswhdx volumio[776]: info:
Aug 30 10:59:48 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 10:59:48 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 10:59:48 hswhdx volumio[776]: info:
Aug 30 10:59:48 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 10:59:48 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 10:59:48 hswhdx volumio[776]: info: sendMpdCommand status took 6 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 10:59:48 hswhdx volumio[776]: info: sendMpdCommand status took 7 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 4 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 10:59:48 hswhdx volumio[776]: info: ControllerMpd::pushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::servicePushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 10:59:48 hswhdx volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"239899179&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/239899179&soundQuality=HI_RES","trackType":"tidal"}
Aug 30 10:59:48 hswhdx volumio[776]: verbose: CURRENT POSITION 34
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::syncState stateService play
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::syncState currentStatus stop
Aug 30 10:59:48 hswhdx volumio[776]: info: ------------------------------ 24ms
Aug 30 10:59:48 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 9 milliseconds
Aug 30 10:59:48 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 10:59:48 hswhdx volumio[776]: info: ControllerMpd::pushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::servicePushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 10:59:48 hswhdx volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":456,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"239899179&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/239899179&soundQuality=HI_RES","trackType":"tidal"}
Aug 30 10:59:48 hswhdx volumio[776]: verbose: CURRENT POSITION 34
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::syncState stateService play
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::syncState currentStatus play
Aug 30 10:59:48 hswhdx volumio[776]: info: Received an update from plugin. extracting info from payload
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 10:59:48 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 10:59:48 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:48.924+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_PLAYING positionMs=0 volume=100
Aug 30 10:59:48 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:48.924+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=tidal://song/239899179 title="Piano Concerto No. 2 in G Minor Op. 22: III. Presto"
Aug 30 10:59:48 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:48.926+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_PLAYING positionMs=0 volume=100
Aug 30 10:59:48 hswhdx volumio5-onboarding[1089]: time=2026-08-30T10:59:48.927+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=tidal://song/239899179 title="Piano Concerto No. 2 in G Minor Op. 22: III. Presto"
Aug 30 10:59:48 hswhdx volumio[776]: info: ------------------------------ 79ms
Aug 30 10:59:54 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 10:59:54 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: tidal , goto
Aug 30 10:59:55 hswhdx volumio[776]: info: explodeTIDALUri took 631 milliseconds
Aug 30 10:59:56 hswhdx volumio[776]: info: browseTIDALUri took 1095 milliseconds
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:56 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:58 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:58 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 10:59:58 hswhdx volumio[776]: info: Executing endpoint metavolumio
Aug 30 10:59:58 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 30 11:00:05 hswhdx volumio[776]: STREAMING PROXY: Upstream closed. Received 41848193 bytes
Aug 30 11:00:05 hswhdx volumio[776]: STREAMING PROXY: Client closed connection
Aug 30 11:00:07 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioReplaceandPlayItems
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::ClearQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::stop
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::stPlaybackTimer
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::serviceStop
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrack 34
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::serviceStop
Aug 30 11:00:07 hswhdx volumio[776]: info: [1788055207264] ControllerTidal::stop
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 30 11:00:07 hswhdx volumio[776]: info: ControllerMpd::stop
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand stop
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::clearPlayQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::addQueueItems
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::addQueueItems
Aug 30 11:00:07 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899177
Aug 30 11:00:07 hswhdx volumio[776]: info: Using cached record of: tidal://song/239899177
Aug 30 11:00:07 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:07.276+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_STOPPED positionMs=0 volume=100
Aug 30 11:00:07 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:07.276+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=tidal://song/239899179 title="Piano Concerto No. 2 in G Minor Op. 22: III. Presto"
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioPlay
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::play index 0
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::addQueueItems
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::addQueueItems
Aug 30 11:00:07 hswhdx volumio[776]: info: Preload queue cleared
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899178
Aug 30 11:00:07 hswhdx volumio[776]: info: Using cached record of: tidal://song/239899178
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899179
Aug 30 11:00:07 hswhdx volumio[776]: info: Using cached record of: tidal://song/239899179
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899180
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899180 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899183
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899183 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899184
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899184 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899185
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899185 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899186
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899186 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899187
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899187 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899188
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899188 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899189
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899189 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899190
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899190 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899191
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899191 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899192
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899192 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899193
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899193 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899194
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899194 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899195
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899195 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899196
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899196 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899197
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899197 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899198
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899198 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899199
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899199 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899200
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899200 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899201
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899201 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899181
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899181 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: Adding Item to queue: tidal://song/239899182
Aug 30 11:00:07 hswhdx volumio[776]: info: Exploding uri tidal://song/239899182 in service tidal
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::stop
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::play index undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::startPlaybackTimer
Aug 30 11:00:07 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 30 11:00:07 hswhdx volumio[776]: info: [1788055207348] ControllerTidal::clearAddPlayTrack
Aug 30 11:00:07 hswhdx volumio[776]: info: Getting stream with soundQuality HI_RES
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand stop
Aug 30 11:00:07 hswhdx volumio[776]: info:
Aug 30 11:00:07 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand stop took 226 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand stop took 144 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand clear
Aug 30 11:00:07 hswhdx volumio[776]: info:
Aug 30 11:00:07 hswhdx volumio[776]: ---------------------------- MPD announces system playlist update
Aug 30 11:00:07 hswhdx volumio[776]: info: Ignoring MPD Status Update
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand status took 7 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand clear took 6 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/239899177&soundQuality=HI_RES"
Aug 30 11:00:07 hswhdx volumio[776]: error: updateQueue error: null
Aug 30 11:00:07 hswhdx volumio[776]: info:
Aug 30 11:00:07 hswhdx volumio[776]: ---------------------------- MPD announces system playlist update
Aug 30 11:00:07 hswhdx volumio[776]: info: Ignoring MPD Status Update
Aug 30 11:00:07 hswhdx volumio[776]: info: ------------------------------ 7ms
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 4 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/239899177&soundQuality=HI_RES" took 4 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreStateMachine::setConsumeUpdateService mpd
Aug 30 11:00:07 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand play
Aug 30 11:00:07 hswhdx volumio[776]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Aug 30 11:00:07 hswhdx volumio[776]: info: ------------------------------ 19ms
Aug 30 11:00:07 hswhdx volumio[776]: STREAMING PROXY: Handling url /?data=tidal://song/239899177&soundQuality=HI_RES
Aug 30 11:00:07 hswhdx volumio[776]: info: ------------------------------ 7ms
Aug 30 11:00:07 hswhdx volumio[776]: info: sendMpdCommand play took 3 milliseconds
Aug 30 11:00:07 hswhdx volumio[776]: info: Executing endpoint getStreamUrltidal
Aug 30 11:00:07 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1342 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1338 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1339 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1355 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1353 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1350 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1355 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1362 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1358 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1382 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1377 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1376 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1389 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1435 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1472 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1473 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1521 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1584 milliseconds
Aug 30 11:00:08 hswhdx volumio[776]: info: explodeTIDALUri took 1593 milliseconds
Aug 30 11:00:09 hswhdx volumio[776]: info: explodeTIDALUri took 1697 milliseconds
Aug 30 11:00:09 hswhdx volumio[776]: info: explodeTIDALUri took 1742 milliseconds
Aug 30 11:00:09 hswhdx volumio[776]: info: explodeTIDALUri took 1778 milliseconds
Aug 30 11:00:09 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushQueue
Aug 30 11:00:09 hswhdx volumio[776]: info: CorePlayQueue::saveQueue
Aug 30 11:00:09 hswhdx volumio[776]: info: CoreStateMachine::updateTrackBlock
Aug 30 11:00:09 hswhdx volumio[776]: info: CorePlayQueue::getTrackBlock
Aug 30 11:00:09 hswhdx volumio[776]: info: getStreamUrl took 1862 milliseconds
Aug 30 11:00:09 hswhdx volumio[776]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic1ZGMyMDkwODA0ZGVhMjA4YWVjZGE2YmY3MjY4MGNhZl82MS5tcDQ/0.flac?token=1788058809~NzdjZGE2MjQ4ZDVhM2VmNjAxODk0MmY5N2EwY2Q5NDdiYTM4NTRhNw==
Aug 30 11:00:09 hswhdx volumio[776]: STREAMING PROXY: Requesting upstream with range: none
Aug 30 11:00:09 hswhdx volumio[776]: STREAMING PROXY: Upstream status code: 200
Aug 30 11:00:10 hswhdx volumio[776]: info:
Aug 30 11:00:10 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 11:00:10 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 11:00:10 hswhdx volumio[776]: info:
Aug 30 11:00:10 hswhdx volumio[776]: ---------------------------- MPD announces state update: player
Aug 30 11:00:10 hswhdx volumio[776]: info: sendMpdCommand status took 5 milliseconds
Aug 30 11:00:10 hswhdx volumio[776]: info: ControllerMpd::getState
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand status
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 11:00:10 hswhdx volumio[776]: info: sendMpdCommand status took 6 milliseconds
Aug 30 11:00:10 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 5 milliseconds
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::parseState
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 11:00:10 hswhdx volumio[776]: info: ControllerMpd::pushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreCommandRouter::servicePushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 11:00:10 hswhdx volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":673,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"239899177&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/239899177&soundQuality=HI_RES","trackType":"tidal"}
Aug 30 11:00:10 hswhdx volumio[776]: verbose: CURRENT POSITION 0
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::syncState stateService play
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::syncState currentStatus stop
Aug 30 11:00:10 hswhdx volumio[776]: info: ------------------------------ 26ms
Aug 30 11:00:10 hswhdx volumio[776]: info: sendMpdCommand playlistinfo took 9 milliseconds
Aug 30 11:00:10 hswhdx volumio[776]: verbose: ControllerMpd::parseTrackInfo
Aug 30 11:00:10 hswhdx volumio[776]: info: ControllerMpd::pushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreCommandRouter::servicePushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CorePlayQueue::getTrack 0
Aug 30 11:00:10 hswhdx volumio[776]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":673,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"94 Kbps","isStreaming":false,"title":"239899177&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/239899177&soundQuality=HI_RES","trackType":"tidal"}
Aug 30 11:00:10 hswhdx volumio[776]: verbose: CURRENT POSITION 0
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::syncState stateService play
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::syncState currentStatus play
Aug 30 11:00:10 hswhdx volumio[776]: info: Received an update from plugin. extracting info from payload
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreStateMachine::pushState
Aug 30 11:00:10 hswhdx volumio[776]: info: CoreCommandRouter::volumioPushState
Aug 30 11:00:10 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:10.079+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_PLAYING positionMs=0 volume=100
Aug 30 11:00:10 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:10.080+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=tidal://song/239899177 title="Piano Concerto No. 2 in G Minor Op. 22: I. Andante sostenuto"
Aug 30 11:00:10 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:10.082+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" state=STATUS_PLAYING positionMs=0 volume=100
Aug 30 11:00:10 hswhdx volumio5-onboarding[1089]: time=2026-08-30T11:00:10.083+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.195:43902 @ 0x20015c0" id=tidal://song/239899177 title="Piano Concerto No. 2 in G Minor Op. 22: I. Andante sostenuto"
Aug 30 11:00:10 hswhdx volumio[776]: info: ------------------------------ 84ms
Aug 30 11:00:26 hswhdx volumio[776]: STREAMING PROXY: Upstream closed. Received 60193045 bytes
Aug 30 11:00:26 hswhdx volumio[776]: STREAMING PROXY: Client closed connection
Aug 30 11:00:38 hswhdx volumio[776]: info: CoreCommandRouter::volumioSaveQueueToPlaylist
Aug 30 11:00:38 hswhdx volumio[776]: info: CoreStateMachine::getQueue
Aug 30 11:00:38 hswhdx volumio[776]: info: CorePlayQueue::getQueue
Aug 30 11:00:38 hswhdx volumio[776]: info: Saving Cloud item Shura Cherkassky plays Camille Saint-Saens: Piano Concerto No. 2 & Franz Liszt: Piano Concerto No. 1
Aug 30 11:00:38 hswhdx volumio[776]: info: CoreCommandRouter::executeOnPlugin: my_volumio , saveCloudItem
Aug 30 11:00:38 hswhdx volumio[776]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 30 11:00:38 hswhdx volumio[776]: Error: Reference.child failed: First argument was an invalid path = "/user_data/Y1tqxwgnPBO41HjZ1ERZzk9xxef1/myPlaylists/Shura Cherkassky plays Camille Saint-Saens: Piano Concerto No. 2 & Franz Liszt: Piano Concerto No. 1". Paths must be non-empty strings and can't contain ".", "#", "$", "[", or "]"
Aug 30 11:00:38 hswhdx volumio[776]: at validatePathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1667:15)
Aug 30 11:00:38 hswhdx volumio[776]: at validateRootPathString (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:1679:5)
Aug 30 11:00:38 hswhdx volumio[776]: at Reference.child (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:13737:17)
Aug 30 11:00:38 hswhdx volumio[776]: at Database.ref (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:15115:48)
Aug 30 11:00:38 hswhdx volumio[776]: at myVolumio.syncJSONToCloud (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:28817)
Aug 30 11:00:38 hswhdx volumio[776]: at myVolumio.saveCloudMyPlaylist (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:23840)
Aug 30 11:00:38 hswhdx volumio[776]: at myVolumio.saveCloudItem (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:18337)
Aug 30 11:00:38 hswhdx volumio[776]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32)
Aug 30 11:00:38 hswhdx volumio[776]: at PlaylistManager.saveJSONFile (/volumio/app/playlistManager.js:613:31)
Aug 30 11:00:38 hswhdx volumio[776]: at PlaylistManager.commonAddItemsToPlaylist (/volumio/app/playlistManager.js:834:8)
Aug 30 11:00:38 hswhdx volumio[776]: at CoreCommandRouter.volumioSaveQueueToPlaylist (/volumio/app/index.js:1647:36)
Aug 30 11:00:38 hswhdx volumio[776]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1479:45)
Aug 30 11:00:38 hswhdx volumio[776]: at Socket.emit (events.js:315:20)
Aug 30 11:00:38 hswhdx volumio[776]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Aug 30 11:00:38 hswhdx volumio[776]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Aug 30 11:00:38 hswhdx volumio[776]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 30 11:00:38 hswhdx sudo[14994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 10:59
Aug 30 11:00:38 hswhdx sudo[14994]: 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"