Feb 12 21:43:17 volumio go-librespot[1493]: time="2026-02-12T21:43:17+09:00" level=trace msg="sent dealer ping"
Feb 12 21:43:17 volumio go-librespot[1493]: time="2026-02-12T21:43:17+09:00" level=trace msg="received dealer pong"
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.156+09:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.10.5:45240
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.193+09:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.572309ms platform=PLATFORM_ANDROID version=5.260120.0
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.193+09:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-28.549707ms timeout=10s
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.193+09:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480"
Feb 12 21:43:24 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:24 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.195+09:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" name=Volumio
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.195+09:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" language=ko
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.196+09:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" timezone=Asia/Seoul
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.196+09:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" available=true connected=true macAddress=08:62:66:11:4d:92 ip4Address=192.168.10.38/24 ip6Address=
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.197+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" available=true connected=false macAddress= ip4Address= ip6Address= ssid=
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.197+09:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" setupComplete=true
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 8
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 8","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 8\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 7
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 3
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: amixer -c 5 info | grep "SMSL USB AUDIO"
Feb 12 21:43:24 volumio volumio[1133]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-4, high speed'
Feb 12 21:43:24 volumio volumio[1133]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Feb 12 21:43:24 volumio volumio[1133]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Feb 12 21:43:24 volumio volumio[1133]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Feb 12 21:43:24 volumio volumio[1133]: amixer -c 5 info | grep "SMSL USB AUDIO"
Feb 12 21:43:24 volumio volumio[1133]: Card sysdefault:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-0000:00:14.0-4, high speed'
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.290+09:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" selectedOutputId=5
Feb 12 21:43:24 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:24 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.324+09:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" currentVersion=4.097 latestVersion=4.097
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.324+09:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" status=UPDATE_STATUS_NONE progress=0
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.324+09:00 level=INFO msg="emitting user changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" userId=
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.324+09:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" providers=3
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.325+09:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" plugins=44
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.327+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PAUSED positionMs=7176 volume=100
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.327+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:24 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:24 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:24 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.10.38:3000 from 192.168.10.5 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 12 21:43:24 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 12 21:43:24 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:24.622+09:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4
Feb 12 21:43:25 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:25.215+09:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-23.513628ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE
Feb 12 21:43:25 volumio sudo[253054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 12 21:43:25 volumio sudo[253054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 21:43:25 volumio sudo[253054]: pam_unix(sudo:session): session closed for user root
Feb 12 21:43:25 volumio sudo[253056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 12 21:43:25 volumio sudo[253056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 21:43:25 volumio sudo[253056]: pam_unix(sudo:session): session closed for user root
Feb 12 21:43:25 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.10.38 from 192.168.10.5 UA: Mozilla/5.0 (Linux; Android 15; SM-F926N Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Feb 12 21:43:26 volumio sudo[253074]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 12 21:43:26 volumio sudo[253074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 21:43:26 volumio sudo[253074]: pam_unix(sudo:session): session closed for user root
Feb 12 21:43:26 volumio sudo[253076]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 12 21:43:26 volumio sudo[253076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 12 21:43:26 volumio sudo[253076]: pam_unix(sudo:session): session closed for user root
Feb 12 21:43:26 volumio volumio[1133]: verbose: New Socket.io Connection to 192.168.10.38 from 192.168.10.5 UA: Mozilla/5.0 (Linux; Android 15; SM-F926N Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 12 21:43:26 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:26 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:26 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:26 volumio volumio[1133]: info: Listing playlists
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 21:43:26 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Feb 12 21:43:27 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 21:43:28 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:28 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:43:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:28 volumio volumio[1133]: info: ControllerMpd::resume
Feb 12 21:43:28 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:43:28 volumio volumio[1133]: info:
Feb 12 21:43:28 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:28 volumio volumio[1133]: info: sendMpdCommand play took 22 milliseconds
Feb 12 21:43:28 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:28 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:28 volumio volumio[1133]: info: sendMpdCommand status took 5 milliseconds
Feb 12 21:43:28 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:28 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:28 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds
Feb 12 21:43:28 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:28 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:28 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8617,"duration":356,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Fortune Teller","artist":"Fourplay","album":"Energy","uri":"NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff","trackType":"dff"}
Feb 12 21:43:28 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus pause
Feb 12 21:43:28 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:28 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:28 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:28 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:28.267+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=7176 volume=100
Feb 12 21:43:28 volumio volumio[1133]: info: ------------------------------ 7ms
Feb 12 21:43:28 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:28.268+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:28 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 12 21:43:29 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:29 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:29 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:29 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:29 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:31 volumio volumio[1133]: info: CoreCommandRouter::volumioGetQueue
Feb 12 21:43:31 volumio volumio[1133]: info: CoreStateMachine::getQueue
Feb 12 21:43:31 volumio volumio[1133]: info: CorePlayQueue::getQueue
Feb 12 21:43:34 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 12 21:43:34 volumio volumio[1133]: info: CURURI: music-library
Feb 12 21:43:34 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:43:35 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 12 21:43:35 volumio volumio[1133]: info: CURURI: music-library/NAS
Feb 12 21:43:35 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:43:36 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Feb 12 21:43:36 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 12 21:43:36 volumio volumio[1133]: info: CURURI: music-library/NAS/FLAC
Feb 12 21:43:36 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::volumioPause
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::pause
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::servicePause
Feb 12 21:43:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::servicePause
Feb 12 21:43:39 volumio volumio[1133]: info: ControllerMpd::pause
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand pause
Feb 12 21:43:39 volumio volumio[1133]: info:
Feb 12 21:43:39 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:39 volumio volumio[1133]: info: sendMpdCommand pause took 8 milliseconds
Feb 12 21:43:39 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:39 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:39 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:39 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:39 volumio volumio[1133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19618,"duration":356,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Fortune Teller","artist":"Fourplay","album":"Energy","uri":"NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff","trackType":"dff"}
Feb 12 21:43:39 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::syncState stateService pause
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus pause
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:43:39 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:39.288+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PAUSED positionMs=18212 volume=100
Feb 12 21:43:39 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:39.288+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:39 volumio volumio[1133]: info: ------------------------------ 7ms
Feb 12 21:43:39 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:39 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:39 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:43:39 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:39 volumio volumio[1133]: info: ControllerMpd::resume
Feb 12 21:43:39 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:43:41 volumio volumio[1133]: info:
Feb 12 21:43:41 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:41 volumio volumio[1133]: info: sendMpdCommand play took 1901 milliseconds
Feb 12 21:43:41 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:41 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:41 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:43:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:41 volumio volumio[1133]: info: ControllerMpd::resume
Feb 12 21:43:41 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:43:41 volumio volumio[1133]: info: sendMpdCommand status took 7 milliseconds
Feb 12 21:43:41 volumio volumio[1133]: info: sendMpdCommand play took 4 milliseconds
Feb 12 21:43:41 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:41 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:41 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 0 milliseconds
Feb 12 21:43:41 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:41 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:41 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:41 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22492,"duration":356,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Fortune Teller","artist":"Fourplay","album":"Energy","uri":"NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff","trackType":"dff"}
Feb 12 21:43:41 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus pause
Feb 12 21:43:41 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:41 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:41 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:41 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:41 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:41.302+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=20114 volume=100
Feb 12 21:43:41 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:41.302+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:41 volumio volumio[1133]: info: ------------------------------ 11ms
Feb 12 21:43:41 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:42 volumio volumio[1133]: info: CoreCommandRouter::volumioPause
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::pause
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::servicePause
Feb 12 21:43:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:42 volumio volumio[1133]: info: CoreCommandRouter::servicePause
Feb 12 21:43:42 volumio volumio[1133]: info: ControllerMpd::pause
Feb 12 21:43:42 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand pause
Feb 12 21:43:42 volumio volumio[1133]: info:
Feb 12 21:43:42 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:42 volumio volumio[1133]: info: sendMpdCommand pause took 1 milliseconds
Feb 12 21:43:42 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:42 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:42 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds
Feb 12 21:43:42 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:42 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:42 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 12 21:43:42 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:42 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:42 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:42 volumio volumio[1133]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23364,"duration":356,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Fortune Teller","artist":"Fourplay","album":"Energy","uri":"NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff","trackType":"dff"}
Feb 12 21:43:42 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::syncState stateService pause
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus pause
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:42 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:42 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:42 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:42 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:43:42 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:42.237+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PAUSED positionMs=20983 volume=100
Feb 12 21:43:42 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:42.237+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:42 volumio volumio[1133]: info: ------------------------------ 5ms
Feb 12 21:43:42 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:44 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Feb 12 21:43:44 volumio volumio[1133]: info: CURURI: music-library/NAS/FLAC/Jazz
Feb 12 21:43:44 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 12 21:43:45 volumio volumio[1133]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Feb 12 21:43:45 volumio volumio[1133]: info: Received Get System Version
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 12 21:43:45 volumio volumio[1133]: info: Received Get System Info
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 12 21:43:45 volumio volumio[1133]: info: Discovery: Getting this device information
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::volumioGetState
Feb 12 21:43:45 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:45 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 12 21:43:47 volumio go-librespot[1493]: time="2026-02-12T21:43:47+09:00" level=trace msg="sent dealer ping"
Feb 12 21:43:47 volumio go-librespot[1493]: time="2026-02-12T21:43:47+09:00" level=trace msg="received dealer pong"
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::ClearQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::serviceStop
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::serviceStop
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::stop
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::addQueueItems
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::addQueueItems
Feb 12 21:43:51 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:43:51 volumio volumio[1133]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Al Di Meola - Casino
Feb 12 21:43:51 volumio volumio[1133]: info: Exploding uri music-library/NAS/FLAC/Jazz/Al Di Meola - Casino in service mpd
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.679+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.679+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff" title="1 - Fortune Teller"
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F01%20Al%20Di%20Meola%20-%20LADO%201-%20Egyptian%20Danza.flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F01.%20LADO%201-%20Egyptian%20Danza.mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01. LADO 1- Egyptian Danza.mp3
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F02%20Al%20Di%20Meola%20-%20Chasin'%20The%20Voodoo.flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/02 Al Di Meola - Chasin' The Voodoo.flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F02.%20Chasin'%20The%20Voodoo.mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/02. Chasin' The Voodoo.mp3
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F03%20Al%20Di%20Meola%20-%20Dark%20Eye%20Tango.flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/03 Al Di Meola - Dark Eye Tango.flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F03.%20Dark%20Eye%20Tango.mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/03. Dark Eye Tango.mp3
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F04%20Al%20Di%20Meola%20-%20LADO%202-%20Senor%20Mouse.flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/04 Al Di Meola - LADO 2- Senor Mouse.flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F04.%20LADO%202-%20Senor%20Mouse.mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/04. LADO 2- Senor Mouse.mp3
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F05%20Al%20Di%20Meola%20-%20Fantasia%20Suite%20For%20Two%20Guitars%20(4%20Parts).flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/05 Al Di Meola - Fantasia Suite For Two Guitars (4 Parts).flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F05.%20Fantasia%20Suite%20For%20Two%20Guitars%20(4%20Parts).mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/05. Fantasia Suite For Two Guitars (4 Parts).mp3
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F06%20Al%20Di%20Meola%20-%20Casino.flac&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/06 Al Di Meola - Casino.flac
Feb 12 21:43:51 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Casino%20(LP)/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Casino%2FAl%20Di%20Meola%20%E2%80%8E-%20Casino%2F06.%20Casino.mp3&metadata=false
Feb 12 21:43:51 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/06. Casino.mp3
Feb 12 21:43:51 volumio volumio[1133]: info:
Feb 12 21:43:51 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand stop took 35 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::play index 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand stop took 0 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clear
Feb 12 21:43:51 volumio volumio[1133]: info:
Feb 12 21:43:51 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:43:51 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 2 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand clear took 1 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac"
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: 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":"Fortune Teller","artist":"Fourplay","album":"Energy","uri":"NAS/DSD/Jazz/Fourplay - Energy 2008 [DSD]/01 - Fourplay - Fortune Teller.dff","trackType":"dff"}
Feb 12 21:43:51 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio[1133]: info: No code
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.692+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.692+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:43:51 volumio volumio[1133]: info: ------------------------------ 11ms
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.693+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.693+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.693+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.693+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:43:51 volumio volumio[1133]: error: updateQueue error: null
Feb 12 21:43:51 volumio volumio[1133]: info:
Feb 12 21:43:51 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:43:51 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:43:51 volumio volumio[1133]: info: ------------------------------ 11ms
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand add "NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" took 8 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:51 volumio volumio[1133]: info: ------------------------------ 6ms
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand play took 7 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: info:
Feb 12 21:43:51 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:51 volumio volumio[1133]: info:
Feb 12 21:43:51 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand status took 1 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand status took 18 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 17 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":359,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"416 Kbps","isStreaming":false,"title":"LADO 1- Egyptian Danza","artist":"Al Di Meola","album":"Casino (LP)","uri":"NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac","trackType":"flac"}
Feb 12 21:43:51 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:43:51 volumio volumio[1133]: info: ------------------------------ 20ms
Feb 12 21:43:51 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 12 21:43:51 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:43:51 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":359,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"416 Kbps","isStreaming":false,"title":"LADO 1- Egyptian Danza","artist":"Al Di Meola","album":"Casino (LP)","uri":"NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac","trackType":"flac"}
Feb 12 21:43:51 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play
Feb 12 21:43:51 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:43:51 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:43:51 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.823+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=990 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.823+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.824+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=990 volume=100
Feb 12 21:43:51 volumio volumio5-onboarding[1449]: time=2026-02-12T21:43:51.824+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:43:51 volumio volumio[1133]: info: ------------------------------ 46ms
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:43:51 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::ClearQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::serviceStop
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::serviceStop
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::stop
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::addQueueItems
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::addQueueItems
Feb 12 21:44:02 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:44:02 volumio volumio[1133]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]
Feb 12 21:44:02 volumio volumio[1133]: info: Exploding uri music-library/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC] in service mpd
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.725+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.725+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola \u200e- Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac" title="1 - LADO 1- Egyptian Danza"
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F01.%20Al%20di%20Meola%20-%20Land%20Of%20The%20Midnight%20Sun.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F02.%20Al%20di%20Meola%20-%20Race%20With%20Th%20Devil%20On%20Spanish%20Highway.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/02. Al di Meola - Race With Th Devil On Spanish Highway.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F03.%20Al%20di%20Meola%20-%20Elegant%20Gypsy%20Suite.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/03. Al di Meola - Elegant Gypsy Suite.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F04.%20Al%20di%20Meola%20-%20Senor%20Mouse.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/04. Al di Meola - Senor Mouse.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F05.%20Al%20di%20Meola%20-%20The%20Wizard.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/05. Al di Meola - The Wizard.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F06.%20Al%20di%20Meola%20-%20Alien%20Chase%20On%20Arabian%20Desert.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/06. Al di Meola - Alien Chase On Arabian Desert.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F07.%20Al%20di%20Meola%20-%20Dinner%20Music%20Of%20The%20Goods.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/07. Al di Meola - Dinner Music Of The Goods.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F08.%20Al%20di%20Meola%20-%20Bianca's%20Midnight%20Lullaby.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/08. Al di Meola - Bianca's Midnight Lullaby.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F09.%20Al%20di%20Meola%20-%20Electric%20Rendezvous.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/09. Al di Meola - Electric Rendezvous.flac
Feb 12 21:44:02 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20di%20Meola/Jazz%20Masters/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20di%20Meola%20-%20Jazz%20Masters%20(2001)%20%5BFLAC%5D%2F10.%20Al%20di%20Meola%20-%20Al%20Di's%20Dream%20Theme.flac&metadata=false
Feb 12 21:44:02 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/10. Al di Meola - Al Di's Dream Theme.flac
Feb 12 21:44:02 volumio volumio[1133]: info:
Feb 12 21:44:02 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand stop took 13 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::play index 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand status took 2 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand stop took 1 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clear
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: 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":"LADO 1- Egyptian Danza","artist":"Al Di Meola","album":"Casino (LP)","uri":"NAS/FLAC/Jazz/Al Di Meola - Casino/Al Di Meola - Casino/01 Al Di Meola - LADO 1- Egyptian Danza.flac","trackType":"flac"}
Feb 12 21:44:02 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio[1133]: info: No code
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.737+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.737+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.737+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.738+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.738+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.738+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:02 volumio volumio[1133]: info: ------------------------------ 9ms
Feb 12 21:44:02 volumio volumio[1133]: info:
Feb 12 21:44:02 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:44:02 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand clear took 5 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac"
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: error: updateQueue error: null
Feb 12 21:44:02 volumio volumio[1133]: info:
Feb 12 21:44:02 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:44:02 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:44:02 volumio volumio[1133]: info: ------------------------------ 2ms
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand add "NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" took 2 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:44:02 volumio volumio[1133]: info: ------------------------------ 1ms
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand play took 1 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: info:
Feb 12 21:44:02 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:02 volumio volumio[1133]: info:
Feb 12 21:44:02 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand status took 61 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand status took 7 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 7 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":555,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Land Of The Midnight Sun","artist":"Al di Meola","album":"Jazz Masters","uri":"NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac","trackType":"flac"}
Feb 12 21:44:02 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:44:02 volumio volumio[1133]: info: ------------------------------ 70ms
Feb 12 21:44:02 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 2 milliseconds
Feb 12 21:44:02 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:02 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":555,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"892 Kbps","isStreaming":false,"title":"Land Of The Midnight Sun","artist":"Al di Meola","album":"Jazz Masters","uri":"NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac","trackType":"flac"}
Feb 12 21:44:02 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play
Feb 12 21:44:02 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:02 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:02 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.838+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=844 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.839+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=844 volume=100
Feb 12 21:44:02 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:02.839+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:02 volumio volumio[1133]: info: ------------------------------ 14ms
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:02 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::ClearQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::serviceStop
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::serviceStop
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::stop
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::addQueueItems
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::addQueueItems
Feb 12 21:44:07 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:44:07 volumio volumio[1133]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]
Feb 12 21:44:07 volumio volumio[1133]: info: Exploding uri music-library/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96] in service mpd
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.447+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.447+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac" title="1 - Land Of The Midnight Sun"
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F01.%20Milonga%20Noctiva%20(feat.%20Kemuel%20Roig).flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F02.%20Broken%20Heart.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/02. Broken Heart.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F03.%20Ava's%20Dream%20Sequence%20Lullaby.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/03. Ava's Dream Sequence Lullaby.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F04.%20Cerreto%20Sannita%20(feat.%20Kemuel%20Roig).flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/04. Cerreto Sannita (feat. Kemuel Roig).flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F05.%20Notorious.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/05. Notorious.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F06.%20Frozen%20in%20Time.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/06. Frozen in Time.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F07.%20Escapado.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/07. Escapado.flac
Feb 12 21:44:07 volumio volumio[1133]: info:
Feb 12 21:44:07 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F08.%20Pomp.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/08. Pomp.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F09.%20Left%20Unsaid.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/09. Left Unsaid.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F10.%20Insieme.flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/10. Insieme.flac
Feb 12 21:44:07 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=Al%20Di%20Meola/Opus/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Opus%20(2018)%20%5BFLAC%2024-96%5D%2F11.%20Rebels%20(feat.%20Kemuel%20Roig).flac&metadata=false
Feb 12 21:44:07 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/11. Rebels (feat. Kemuel Roig).flac
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand stop took 28 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPlay
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::play index 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::play index undefined
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::startPlaybackTimer
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::clearAddPlayTracks NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand status took 4 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand stop took 1 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand clear
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: 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":"Land Of The Midnight Sun","artist":"Al di Meola","album":"Jazz Masters","uri":"NAS/FLAC/Jazz/Al di Meola - Jazz Masters (2001) [FLAC]/01. Al di Meola - Land Of The Midnight Sun.flac","trackType":"flac"}
Feb 12 21:44:07 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState stateService stop
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio[1133]: info: No code
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.459+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.459+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:07 volumio volumio[1133]: info: ------------------------------ 10ms
Feb 12 21:44:07 volumio volumio[1133]: info:
Feb 12 21:44:07 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:44:07 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand clear took 4 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand add "NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac"
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.460+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.460+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.460+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.460+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: error: updateQueue error: null
Feb 12 21:44:07 volumio volumio[1133]: info:
Feb 12 21:44:07 volumio volumio[1133]: ---------------------------- MPD announces system playlist update
Feb 12 21:44:07 volumio volumio[1133]: info: Ignoring MPD Status Update
Feb 12 21:44:07 volumio volumio[1133]: info: ------------------------------ 2ms
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand add "NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" took 2 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand play
Feb 12 21:44:07 volumio volumio[1133]: info: ------------------------------ 1ms
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand play took 0 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: info:
Feb 12 21:44:07 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:07 volumio volumio[1133]: info:
Feb 12 21:44:07 volumio volumio[1133]: ---------------------------- MPD announces state update: player
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand status took 31 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::getState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand status
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand status took 0 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseState
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":476,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2194 Kbps","isStreaming":false,"title":"Milonga Noctiva (feat. Kemuel Roig)","artist":"Al Di Meola","album":"Opus","uri":"NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac","trackType":"flac"}
Feb 12 21:44:07 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus stop
Feb 12 21:44:07 volumio volumio[1133]: info: ------------------------------ 36ms
Feb 12 21:44:07 volumio volumio[1133]: info: sendMpdCommand playlistinfo took 3 milliseconds
Feb 12 21:44:07 volumio volumio[1133]: verbose: ControllerMpd::parseTrackInfo
Feb 12 21:44:07 volumio volumio[1133]: info: ControllerMpd::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::servicePushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":476,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2622 Kbps","isStreaming":false,"title":"Milonga Noctiva (feat. Kemuel Roig)","artist":"Al Di Meola","album":"Opus","uri":"NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac","trackType":"flac"}
Feb 12 21:44:07 volumio volumio[1133]: verbose: CURRENT POSITION 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState stateService play
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::syncState currentStatus play
Feb 12 21:44:07 volumio volumio[1133]: info: Received an update from plugin. extracting info from payload
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:07 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:07 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.538+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=990 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.538+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:07 volumio volumio[1133]: info: ------------------------------ 16ms
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.541+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=990 volume=100
Feb 12 21:44:07 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:07.542+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:07 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.315+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-11.816328ms timeout=3s volume=99
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume99
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 100
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 99
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.327+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=4741 volume=99
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.327+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.552+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-12.966764ms timeout=3s volume=98
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume98
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 98
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.567+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=4991 volume=98
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.567+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 100
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 98
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:11 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 98
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.595+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.37322ms timeout=3s volume=97
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume97
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.605+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=4991 volume=97
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.605+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 97
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 98
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 97
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.636+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-24.654505ms timeout=3s volume=96
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume96
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.645+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=4991 volume=96
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.645+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 98
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 96
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:11 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 96
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.686+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.163419ms timeout=3s volume=95
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume95
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.698+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=4991 volume=95
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.699+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 95
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 96
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 95
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.733+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.366083ms timeout=3s volume=94
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume94
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 96
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 94
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:11 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 94
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.748+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5230 volume=94
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.748+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.790+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.784113ms timeout=3s volume=93
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume93
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 93
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.801+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5251 volume=93
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.801+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 94
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 93
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.835+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.429418ms timeout=3s volume=92
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume92
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.845+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5251 volume=92
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.845+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 94
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 92
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:11 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 92
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.886+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.471106ms timeout=3s volume=91
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume91
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 92
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 91
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.896+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5251 volume=91
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.896+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.942+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.752869ms timeout=3s volume=90
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume90
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.956+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5251 volume=90
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.956+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 92
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 90
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:11 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 90
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.988+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.6755ms timeout=3s volume=89
Feb 12 21:44:11 volumio volumio[1133]: info: VolumeController::SetAlsaVolume89
Feb 12 21:44:11 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:11 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:11 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.996+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5481 volume=89
Feb 12 21:44:11 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:11.996+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 90
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 89
Feb 12 21:44:11 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.039+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.895966ms timeout=3s volume=88
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume88
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 90
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 88
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:12 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 88
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.047+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5502 volume=88
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.047+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.093+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-25.570398ms timeout=3s volume=87
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume87
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.101+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5502 volume=87
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.101+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 88
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 87
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.138+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.329006ms timeout=3s volume=86
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume86
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.149+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5502 volume=86
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.149+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 88
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 86
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:12 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 86
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.189+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.673639ms timeout=3s volume=85
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume85
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.197+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5502 volume=85
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.197+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 86
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 85
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.239+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.890389ms timeout=3s volume=84
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume84
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 84
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 86
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 84
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:12 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 84
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.248+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5730 volume=84
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.248+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.296+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.933688ms timeout=3s volume=83
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume83
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.303+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5752 volume=83
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.305+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 84
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 83
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.340+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-26.98408ms timeout=3s volume=82
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume82
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.350+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5752 volume=82
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.350+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 84
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 82
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:12 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 82
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.390+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-27.453505ms timeout=3s volume=81
Feb 12 21:44:12 volumio volumio[1133]: info: VolumeController::SetAlsaVolume81
Feb 12 21:44:12 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:12 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:12 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.400+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=5752 volume=81
Feb 12 21:44:12 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:12.400+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 82
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 81
Feb 12 21:44:12 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 12 21:44:13 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:13.154+09:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.5:45240 @ 0xc0002ce480" latency=-25.963172ms timeout=3s volume=80
Feb 12 21:44:13 volumio volumio[1133]: info: VolumeController::SetAlsaVolume80
Feb 12 21:44:13 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:13 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:13 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:13 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:13 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:13.166+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_PLAYING positionMs=6503 volume=80
Feb 12 21:44:13 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:13.166+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:13 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80
Feb 12 21:44:13 volumio volumio[1133]: SPOTIFY: SPOTIFY VOLUME 82
Feb 12 21:44:13 volumio volumio[1133]: SPOTIFY: VOLUMIO VOLUME 80
Feb 12 21:44:13 volumio volumio[1133]: SPOTIFY: DELTA VOLUME ENOUGH: true
Feb 12 21:44:13 volumio volumio[1133]: info: Setting Spotify Volume from Volumio: 80
Feb 12 21:44:14 volumio volumio[1133]: SPOTIFY: SETTING SPOTIFY VOLUME 80
Feb 12 21:44:14 volumio volumio[1133]: info: Sending Spotify command with payload to local API: /player/volume
Feb 12 21:44:14 volumio go-librespot[1493]: time="2026-02-12T21:44:14+09:00" level=debug msg="update volume requested to 52428/65535"
Feb 12 21:44:14 volumio go-librespot[1493]: time="2026-02-12T21:44:14+09:00" level=debug msg="renewing login5 access token"
Feb 12 21:44:14 volumio go-librespot[1493]: time="2026-02-12T21:44:14+09:00" level=info msg="authenticated Login5" username="31************************5u"
Feb 12 21:44:15 volumio go-librespot[1493]: time="2026-02-12T21:44:15+09:00" level=debug msg="put connect state because VOLUME_CHANGED"
Feb 12 21:44:15 volumio go-librespot[1493]: time="2026-02-12T21:44:15+09:00" level=trace msg="emitting websocket event: volume"
Feb 12 21:44:15 volumio volumio[1133]: SPOTIFY: received: {"type":"volume","data":{"value":80,"max":100}}
Feb 12 21:44:15 volumio volumio[1133]: SPOTIFY: RECEIVED SPOTIFY VOLUME 80
Feb 12 21:44:17 volumio go-librespot[1493]: time="2026-02-12T21:44:17+09:00" level=trace msg="sent dealer ping"
Feb 12 21:44:17 volumio go-librespot[1493]: time="2026-02-12T21:44:17+09:00" level=trace msg="received dealer pong"
Feb 12 21:44:20 volumio volumio[1133]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::ClearQueue
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::stop
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::stPlaybackTimer
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::updateTrackBlock
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::getTrackBlock
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::pushState
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:20 volumio volumio[1133]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 12 21:44:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushState
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::serviceStop
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::getTrack 0
Feb 12 21:44:20 volumio volumio[1133]: info: CoreCommandRouter::serviceStop
Feb 12 21:44:20 volumio volumio[1133]: info: ControllerMpd::stop
Feb 12 21:44:20 volumio volumio[1133]: verbose: ControllerMpd::sendMpdCommand stop
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::clearPlayQueue
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::saveQueue
Feb 12 21:44:20 volumio volumio[1133]: info: CoreCommandRouter::volumioPushQueue
Feb 12 21:44:20 volumio volumio[1133]: info: CoreStateMachine::addQueueItems
Feb 12 21:44:20 volumio volumio[1133]: info: CorePlayQueue::addQueueItems
Feb 12 21:44:20 volumio volumio[1133]: info: Preload queue cleared
Feb 12 21:44:20 volumio volumio[1133]: info: Adding Item to queue: music-library/NAS/FLAC/Jazz/Al Di Meola - Consequence Of Chaos (24-88 SACD 2006) 통파일
Feb 12 21:44:20 volumio volumio[1133]: info: Exploding uri music-library/NAS/FLAC/Jazz/Al Di Meola - Consequence Of Chaos (24-88 SACD 2006) 통파일 in service mpd
Feb 12 21:44:20 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:20.791+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" state=STATUS_STOPPED positionMs=0 volume=80
Feb 12 21:44:20 volumio volumio5-onboarding[1449]: time=2026-02-12T21:44:20.791+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.5:45240 @ 0xc0002ce480" id="mnt/NAS/FLAC/Jazz/Al Di Meola - Opus (2018) [FLAC 24-96]/01. Milonga Noctiva (feat. Kemuel Roig).flac" title="1 - Milonga Noctiva (feat. Kemuel Roig)"
Feb 12 21:44:20 volumio volumio[1133]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80
Feb 12 21:44:20 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=/(2006)%20Consequence%20Of%20Chaos%20%5BSACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Consequence%20Of%20Chaos%20(24-88%20SACD%202006)%20%ED%86%B5%ED%8C%8C%EC%9D%BC%2FAl%20Di%20Meola%20-%20(2006)%20Consequence%20Of%20Chaos%20%5BSACD%5D.cue&metadata=false
Feb 12 21:44:20 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Consequence Of Chaos (24-88 SACD 2006) 통파일/Al Di Meola - (2006) Consequence Of Chaos [SACD].cue
Feb 12 21:44:20 volumio volumio[1133]: info: ALBUMART /albumart?cacheid=271&web=/(2006)%20Consequence%20Of%20Chaos%20%5BSACD%5D/medium&path=%2Fmnt%2FNAS%2FFLAC%2FJazz%2FAl%20Di%20Meola%20-%20Consequence%20Of%20Chaos%20(24-88%20SACD%202006)%20%ED%86%B5%ED%8C%8C%EC%9D%BC%2FAl%20Di%20Meola%20-%20(2006)%20Consequence%20Of%20Chaos%20%5BSACD%5D.cue&metadata=false
Feb 12 21:44:20 volumio volumio[1133]: info: URI /mnt/NAS/FLAC/Jazz/Al Di Meola - Consequence Of Chaos (24-88 SACD 2006) 통파일/Al Di Meola - (2006) Consequence Of Chaos [SACD].cue
Feb 12 21:44:20 volumio volumio[1133]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 21:44:20 volumio volumio[1133]: Error: Unable to resolve or reject the same promise twice
Feb 12 21:44:20 volumio volumio[1133]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Feb 12 21:44:20 volumio volumio[1133]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21
Feb 12 21:44:20 volumio volumio[1133]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Feb 12 21:44:20 volumio volumio[1133]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Feb 12 21:44:20 volumio volumio[1133]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Feb 12 21:44:20 volumio volumio[1133]: at Socket.emit (node:events:514:28)
Feb 12 21:44:20 volumio volumio[1133]: at addChunk (node:internal/streams/readable:343:12)
Feb 12 21:44:20 volumio volumio[1133]: at readableAddChunk (node:internal/streams/readable:312:11)
Feb 12 21:44:20 volumio volumio[1133]: at Readable.push (node:internal/streams/readable:253:10)
Feb 12 21:44:20 volumio volumio[1133]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)
Feb 12 21:44:20 volumio volumio[1133]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 12 21:44:20 volumio sudo[253253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 21:43'
Feb 12 21:44:20 volumio sudo[253253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Debian GNU/Linux 12 (bookworm)"
NAME="Debian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="f8b25437696d030ba01136ff33a129a42b97a4aa"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Feb 10 18:22:41 UTC 2026"
VOLUMIO_VERSION="4.097"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="d7204c63afb7bf3420048db04b050c1c"