-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sun 2026-01-11 22:34:53 KST. -- Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=debug msg="handling play player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=trace msg="fetched new page 0 with 125 items (list: 125)" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4Cesp17kIKOhUwQnqzdbG6" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=trace msg="emitting websocket event: will_play" Jan 11 22:33:00 volumio-study volumio[985]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4Cesp17kIKOhUwQnqzdbG6","play_origin":"playlist/ondemand"}} Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:4Cesp17kIKOhUwQnqzdbG6" Jan 11 22:33:00 volumio-study go-librespot[1172]: time="2026-01-11T22:33:00+09:00" level=warning msg="failed handling dealer request" error="failed loading current track (load context): failed creating stream for spotify:track:4Cesp17kIKOhUwQnqzdbG6: no supported formats" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=debug msg="handling play player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=trace msg="fetched new page 0 with 125 items (list: 125)" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1UKn8bOlQO1gakfDhyu1FN" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=trace msg="emitting websocket event: will_play" Jan 11 22:33:09 volumio-study volumio[985]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1UKn8bOlQO1gakfDhyu1FN","play_origin":"playlist/ondemand"}} Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:1UKn8bOlQO1gakfDhyu1FN" Jan 11 22:33:09 volumio-study go-librespot[1172]: time="2026-01-11T22:33:09+09:00" level=warning msg="failed handling dealer request" error="failed loading current track (load context): failed creating stream for spotify:track:1UKn8bOlQO1gakfDhyu1FN: no supported formats" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=debug msg="handling play player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=trace msg="fetched new page 0 with 125 items (list: 125)" uri="spotify:playlist:37i9dQZF1DX06817kK7cRP" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1UKn8bOlQO1gakfDhyu1FN" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=trace msg="emitting websocket event: will_play" Jan 11 22:33:10 volumio-study volumio[985]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1UKn8bOlQO1gakfDhyu1FN","play_origin":"playlist/ondemand"}} Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:1UKn8bOlQO1gakfDhyu1FN" Jan 11 22:33:10 volumio-study go-librespot[1172]: time="2026-01-11T22:33:10+09:00" level=warning msg="failed handling dealer request" error="failed loading current track (load context): failed creating stream for spotify:track:1UKn8bOlQO1gakfDhyu1FN: no supported formats" Jan 11 22:33:11 volumio-study go-librespot[1172]: time="2026-01-11T22:33:11+09:00" level=trace msg="sent dealer ping" Jan 11 22:33:11 volumio-study go-librespot[1172]: time="2026-01-11T22:33:11+09:00" level=trace msg="received dealer pong" Jan 11 22:33:15 volumio-study go-librespot[1172]: time="2026-01-11T22:33:15+09:00" level=debug msg="handling pause player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:15 volumio-study go-librespot[1172]: time="2026-01-11T22:33:15+09:00" level=warning msg="failed handling dealer request" error="no primary stream" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=debug msg="handling play player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO2i8prY" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1DZ06evO2i8prY" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6yKkA8HzwWTZ5taIMaG4Nm" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=trace msg="emitting websocket event: will_play" Jan 11 22:33:22 volumio-study volumio[985]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6yKkA8HzwWTZ5taIMaG4Nm","play_origin":"playlist/ondemand"}} Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:6yKkA8HzwWTZ5taIMaG4Nm" Jan 11 22:33:22 volumio-study go-librespot[1172]: time="2026-01-11T22:33:22+09:00" level=warning msg="failed handling dealer request" error="failed loading current track (load context): failed creating stream for spotify:track:6yKkA8HzwWTZ5taIMaG4Nm: no supported formats" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=info msg="playback was transferred to iPhone" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 456" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2260" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="put connect state inactive" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=trace msg="emitting websocket event: inactive" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=trace msg="emitting websocket event: stopped" Jan 11 22:33:26 volumio-study volumio[985]: SPOTIFY: received: {"type":"inactive","data":null} Jan 11 22:33:26 volumio-study volumio[985]: error: Failed to decode event: inactive Jan 11 22:33:26 volumio-study volumio[985]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Jan 11 22:33:26 volumio-study volumio[985]: SPOTIFY: PUSH STATE SPOTIFY Jan 11 22:33:26 volumio-study volumio[985]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 11 22:33:26 volumio-study volumio[985]: info: CoreCommandRouter::servicePushState Jan 11 22:33:26 volumio-study volumio[985]: info: CoreStateMachine::pushState Jan 11 22:33:26 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:26 volumio-study volumio[985]: info: CoreCommandRouter::volumioPushState Jan 11 22:33:26 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:26 volumio-study volumio[985]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 11 22:33:26 volumio-study volumio[985]: verbose: CURRENT POSITION 0 Jan 11 22:33:26 volumio-study volumio[985]: info: CoreStateMachine::syncState stateService stop Jan 11 22:33:26 volumio-study volumio[985]: info: CoreStateMachine::syncState currentStatus stop Jan 11 22:33:26 volumio-study volumio[985]: info: CoreStateMachine::pushState Jan 11 22:33:26 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:26 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 22:33:26 volumio-study volumio[985]: info: CoreCommandRouter::volumioPushState Jan 11 22:33:26 volumio-study volumio[985]: info: No code Jan 11 22:33:26 volumio-study volumio[985]: info: CoreStateMachine::pushState Jan 11 22:33:26 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:26 volumio-study volumio[985]: info: CoreCommandRouter::volumioPushState Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="dealer connection closed" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="dealer recv loop stopped" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="obtained new client token: AABNdh3jv/9a9QNOPTWP5RYMeMeNkK1ptUmJFLzqiD2uRmERWaaHc0ZeRC9Ot+4nNXaErwC8EuA5DNfFxzExouj1D1YiIvMh/X3O+dgUnnuwAyF2eAfYd4vutr4y1nTnQc7uHHaynTfjgKO2EpgDCpnQJtBft/QHuG29n+jrDMGUTRZpOCrz+lNscfa/xWr5kEyvaxpDk/HdeOCbDUea2W2uRujNqXAaAN2Z1HPc6zWNKAj9Wwt9bAQ1MjQ7" Jan 11 22:33:26 volumio-study go-librespot[1172]: time="2026-01-11T22:33:26+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="completed keyexchange" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="completed challenge" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=info msg="authenticated AP" username="12*****92" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=info msg="authenticated Login5" username="12*****92" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="restored session after logout" username="12*****92" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="dealer connection opened" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=trace msg="starting accesspoint recv loop" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=trace msg="starting dealer recv loop" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=trace msg="received accesspoint ping" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="received connection id: NTIyODQxYWQtZGI0...OUNDMkMyQUM3NA==" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=trace msg="received accesspoint pong ack" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="update volume requested to 65535/65535" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 22:33:27 volumio-study go-librespot[1172]: time="2026-01-11T22:33:27+09:00" level=trace msg="emitting websocket event: volume" Jan 11 22:33:27 volumio-study volumio[985]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 11 22:33:27 volumio-study volumio[985]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 11 22:33:27 volumio-study volumio[985]: info: Setting Volumio Volume from Spotify: 100 Jan 11 22:33:27 volumio-study volumio[985]: info: VolumeController::SetAlsaVolume100 Jan 11 22:33:27 volumio-study volumio[985]: info: CoreStateMachine::pushState Jan 11 22:33:27 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:27 volumio-study volumio[985]: info: CoreCommandRouter::volumioPushState Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="handling transfer player command from e6320e80afbb8571c5fe1558464e2d4754920418" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO2i8prY" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1DZ06evO2i8prY" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="loading track (paused: false, position: 4879ms)" uri="spotify:track:6yKkA8HzwWTZ5taIMaG4Nm" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=trace msg="emitting websocket event: will_play" Jan 11 22:33:31 volumio-study volumio[985]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6yKkA8HzwWTZ5taIMaG4Nm","play_origin":"playlist/ondemand"}} Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:6yKkA8HzwWTZ5taIMaG4Nm" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:6yKkA8HzwWTZ5taIMaG4Nm: no supported formats" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2211" Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 11 22:33:31 volumio-study go-librespot[1172]: time="2026-01-11T22:33:31+09:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 11 22:33:39 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38:3000 from 192.168.100.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 11 22:33:39 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:39 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:39 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:39 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:39 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:39 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:54 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38:3000 from 192.168.100.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 11 22:33:54 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:54 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:54 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:54 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:54 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:54 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:57 volumio-study go-librespot[1172]: time="2026-01-11T22:33:57+09:00" level=trace msg="sent dealer ping" Jan 11 22:33:57 volumio-study go-librespot[1172]: time="2026-01-11T22:33:57+09:00" level=trace msg="received dealer pong" Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:58 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:58 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 11 22:33:58 volumio-study sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 11 22:33:58 volumio-study sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 22:33:58 volumio-study sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1559]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study sudo[1554]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 22:33:58 volumio-study sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1567]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 11 22:33:58 volumio-study sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1573]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 22:33:58 volumio-study sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1579]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 22:33:58 volumio-study sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:58 volumio-study sudo[1582]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:58 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:58 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:58 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:58 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:58 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 22:33:58 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38:3000 from 192.168.100.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 22:33:58 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 22:33:59 volumio-study sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 22:33:59 volumio-study sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:59 volumio-study sudo[1586]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:59 volumio-study sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 22:33:59 volumio-study sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:59 volumio-study sudo[1588]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:59 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.250 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 22:33:59 volumio-study sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 22:33:59 volumio-study sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:59 volumio-study sudo[1592]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:59 volumio-study sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 22:33:59 volumio-study sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 22:33:59 volumio-study sudo[1595]: pam_unix(sudo:session): session closed for user root Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 22:33:59 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38 from 192.168.100.250 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:59 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 22:33:59 volumio-study volumio[985]: info: Received Get System Info Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:33:59 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:59 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:33:59 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:33:59 volumio-study volumio[985]: info: Listing playlists Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 22:33:59 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 22:34:01 volumio-study volumio[985]: info: Received Get System Info Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:34:01 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:34:01 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:34:01 volumio-study volumio[985]: info: CoreCommandRouter::volumioPlay Jan 11 22:34:01 volumio-study volumio[985]: info: CoreStateMachine::play index undefined Jan 11 22:34:01 volumio-study volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 22:34:01 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:34:02 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:34:02 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:34:02 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38:3000 from 192.168.100.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 22:34:02 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::volumioPlay Jan 11 22:34:03 volumio-study volumio[985]: info: CoreStateMachine::play index undefined Jan 11 22:34:03 volumio-study volumio[985]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 22:34:03 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 22:34:03 volumio-study volumio[985]: info: Received Get System Info Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:34:03 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:34:03 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:03 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:34:04 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:34:04 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:34:04 volumio-study volumio[985]: verbose: New Socket.io Connection to 192.168.100.38:3000 from 192.168.100.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 11 22:34:04 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 11 22:34:09 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 22:34:14 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetQueue Jan 11 22:34:14 volumio-study volumio[985]: info: CoreStateMachine::getQueue Jan 11 22:34:14 volumio-study volumio[985]: info: CorePlayQueue::getQueue Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 22:34:18 volumio-study volumio[985]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 22:34:18 volumio-study volumio[985]: info: Received Get System Version Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 22:34:18 volumio-study volumio[985]: info: Received Get System Info Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 22:34:18 volumio-study volumio[985]: info: Discovery: Getting this device information Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetState Jan 11 22:34:18 volumio-study volumio[985]: info: CorePlayQueue::getTrack 0 Jan 11 22:34:18 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: info: Retrieving Cloud Streaming UI Jan 11 22:34:19 volumio-study volumio[985]: info: Getting Tidal Cloud Configuration Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: info: Getting Qobuz Cloud Configuration Jan 11 22:34:19 volumio-study volumio[985]: info: Asking plugin for UI Config Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: info: Getting Spotify Cloud Configuration Jan 11 22:34:19 volumio-study volumio[985]: info: Asking plugin for UI Config Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: info: Saving Spotify Acccount Jan 11 22:34:19 volumio-study volumio[985]: info: Got Tidal Cloud Configuration Jan 11 22:34:19 volumio-study volumio[985]: info: Got it Jan 11 22:34:19 volumio-study volumio[985]: info: Got it Jan 11 22:34:19 volumio-study volumio[985]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:19 volumio-study volumio[985]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:19 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 22:34:23 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:27 volumio-study go-librespot[1172]: time="2026-01-11T22:34:27+09:00" level=trace msg="sent dealer ping" Jan 11 22:34:27 volumio-study go-librespot[1172]: time="2026-01-11T22:34:27+09:00" level=trace msg="received dealer pong" Jan 11 22:34:27 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:31 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:45 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:45 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:45 volumio-study volumio[985]: info: Retrieving Cloud Streaming UI Jan 11 22:34:45 volumio-study volumio[985]: info: Getting Tidal Cloud Configuration Jan 11 22:34:45 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:45 volumio-study volumio[985]: info: Getting Qobuz Cloud Configuration Jan 11 22:34:45 volumio-study volumio[985]: info: Asking plugin for UI Config Jan 11 22:34:45 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:45 volumio-study volumio[985]: info: Getting Spotify Cloud Configuration Jan 11 22:34:45 volumio-study volumio[985]: info: Asking plugin for UI Config Jan 11 22:34:45 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:45 volumio-study volumio[985]: info: Saving Spotify Acccount Jan 11 22:34:45 volumio-study volumio[985]: info: Got Tidal Cloud Configuration Jan 11 22:34:45 volumio-study volumio[985]: info: Got it Jan 11 22:34:45 volumio-study volumio[985]: info: Got it Jan 11 22:34:45 volumio-study volumio[985]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 22:34:46 volumio-study volumio[985]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:46 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 22:34:47 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 11 22:34:47 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 11 22:34:49 volumio-study volumio[985]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L pi-star.local. -m SMB3_11 Jan 11 22:34:49 volumio-study volumio[985]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L volumio-living.local. -m SMB3_11 Jan 11 22:34:49 volumio-study volumio[985]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L honeyjar.local. -m SMB3_11 Jan 11 22:34:49 volumio-study volumio[985]: info: Error browsing device honeyjar for shares: Command failed: smbclient --no-pass --debuglevel=0 -L honeyjar.local. -m SMB3_11 Jan 11 22:34:49 volumio-study volumio[985]: Unable to initialize messaging context Jan 11 22:34:50 volumio-study volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 22:34:52 volumio-study volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 22:34:52 volumio-study volumio[985]: Error: dns service error: unknown Jan 11 22:34:52 volumio-study volumio[985]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 11 22:34:52 volumio-study volumio[985]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 11 22:34:52 volumio-study volumio[985]: errorCode: -65537 Jan 11 22:34:52 volumio-study volumio[985]: } Jan 11 22:34:52 volumio-study volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 22:34:53 volumio-study sudo[1737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 22:33 Jan 11 22:34:53 volumio-study sudo[1737]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"