Aug 26 12:14:01 volumio volumio[1240]: info: VolumeController::SetAlsaVolume85 Aug 26 12:14:01 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.020+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_PLAYING positionMs=5282 volume=85 Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.021+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=https://live9.avf.ch/ipmusicslow320 title="Josh Groban - What I Did For Love *** www.ipmusicslow.ch" Aug 26 12:14:01 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 85 Aug 26 12:14:01 volumio volumio[1240]: info: VolumeController::SetAlsaVolume80 Aug 26 12:14:01 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.121+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_PLAYING positionMs=5563 volume=80 Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.121+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=https://live9.avf.ch/ipmusicslow320 title="Josh Groban - What I Did For Love *** www.ipmusicslow.ch" Aug 26 12:14:01 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 80 Aug 26 12:14:01 volumio volumio[1240]: info: VolumeController::SetAlsaVolume75 Aug 26 12:14:01 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:01 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:01 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.290+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_PLAYING positionMs=5563 volume=75 Aug 26 12:14:01 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:01.290+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=https://live9.avf.ch/ipmusicslow320 title="Josh Groban - What I Did For Love *** www.ipmusicslow.ch" Aug 26 12:14:01 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 75 Aug 26 12:14:02 volumio volumio[1240]: info: VolumeController::SetAlsaVolume80 Aug 26 12:14:02 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:02 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:02 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:02 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:02 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:02 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:02.260+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_PLAYING positionMs=6583 volume=80 Aug 26 12:14:02 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:02.260+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=https://live9.avf.ch/ipmusicslow320 title="Josh Groban - What I Did For Love *** www.ipmusicslow.ch" Aug 26 12:14:02 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 80 Aug 26 12:14:03 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 12:14:06 volumio volumio[1240]: info: CoreCommandRouter::volumioGetQueue Aug 26 12:14:06 volumio volumio[1240]: info: CoreStateMachine::getQueue Aug 26 12:14:06 volumio volumio[1240]: info: CorePlayQueue::getQueue Aug 26 12:14:07 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 12:14:07 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 26 12:14:10 volumio volumio[1240]: info: In handleBrowseUri, curUri=spotify Aug 26 12:14:10 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:10 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:10 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:10 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 12:14:10 volumio volumio[1240]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 26 12:14:10 volumio volumio[1240]: info: Received Get System Version Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 12:14:10 volumio volumio[1240]: info: Received Get System Info Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 12:14:10 volumio volumio[1240]: info: Discovery: Getting this device information Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:10 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 12:14:16 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 26 12:14:16 volumio volumio[1240]: info: In handleBrowseUri, curUri=spotify/playlists Aug 26 12:14:17 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:19 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 26 12:14:19 volumio volumio[1240]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:5LBHmwNMARJem82Ap7qhlA Aug 26 12:14:23 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0CnD7Hiw6pae6rLsZw5q5W Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6BPDPcnbDMDf58srVzbfX9 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3bNv3VuUOKgrf5hu3YcuRo Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3EY1sjf2Xq86pQN0L4hEnU Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2rbDhOo9Fh61Bbu23T2qCk Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:00YfGEWo5ebpbue5cQmtuR Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1ZNcJrNdX4HbO6NGj8tlaL Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1iEr3OkczMbk557RgIMtxm Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0mEBHoIHl8UOW0jWMIwmAw Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6E8nJ1vXBkbGm9m1oWdneC Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:19vLbTpnjkQtcCTaHVPQUm Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5SEKw7xbTk21uulHDgPnhR Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5surqTVBdeN36V9WZSjkWE Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7AjYzHBfYycWYSloHJf7i2 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6Fc5TJKT60jBX4JhqVBCFY Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6qCGPzPxysw0TdF3y3wqY8 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6QGJ6M3Mubu8r8QtHVK5kb Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1L5UD1mNJi7Od9tJDzDCx9 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4Ul8dYosUhI8kTJWluukpv Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4i8D5dh2n2B1mUWgQSC5ZO Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1ZBD3JaXuUuA4bgpIi7jwG Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5ehXkLVhU8uDyoKpSwgvBH Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2HpfAl9P2pqVBRez532tF6 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7ySzRATA20XTnmk7CVXOTV Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7c5eOfolAGuVwEaulxdvNJ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6vZZtivPrtpggT5SSXsYTy Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4xh7W7tlNMIczFhupCPniY Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6DMOzL2zPZBAW0LDYCYLWm Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4ggQZbM68glGfoH1kImIwO Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7IPMUKKlLQEBuKVhNzwTR1 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7m4rsekmwWrti3eg7l2RO4 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3TW48DvQ7ChwY1Yy1tkMaP Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0NI9wGowTO80hVs3l6wa0i Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6BD1X1PeV5UzYUdiVaD2yL Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0jyElqUm59deyol951jPsr Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2pSUpHpEOWgy0YJZO0tP5D Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:34hTNaJabBsvep2uCmfl9X Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:18UC721duMMiQRaWhyB8eU Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6VaM0Vtj0tgtDTvJgVu2yy Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3qGl6bfdUNjgDG3fvHZVAu Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3OwtuzRo8mc7lgsBau9MWk Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6wKqeNPpoLsximg7fRHasF Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5GxejRsquV2JEu9TNGmWc6 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1wwKY8ICsV9wiCFMpdhPrr Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5y788ya4NvwhBznoDIcXwK Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4WpmwEdc4RBos8W8icSSxW Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0w3ijrVQ2Q4U0guO2WhKaE Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:142Cyvjn8CLONFRDTK7SGh Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5VYB1J9aXyYxjE5Z8kXRgK Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5ImoRAws2ni1qYWrLR7sqi Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0a0In4Eo07yoETGNey6Ue4 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4hpksgtpDgspt9UtkDnCMS Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3NLrRZoMF0Lx6zTlYqeIo4 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5MxNLUsfh7uzROypsoO5qe Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3nngQ14mffvvcoqkcJ5FET Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1SfdzhXyrw84Hs0oMLaeEC Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7ofV2J7Ndzo2s5NBEgfpxl Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4BfbGIR6urmMtOaCXv2pCT Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:18I9sjv8BJpSSNDtlOdGrL Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2YGLD3hQFE2f95vc1LgMlz Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6l6b7epmL1Eg7ZlxYqMVed Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6y5DbHoQMeqLVrWRhRMN7X Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2GxGYGrixbXPFojONbzdo9 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1AWSemPzuGu4A9lVhSsFWJ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:37ULGsYVNJmXxCVup7rTTu Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4aYLAF6ckQ5ooGGGM7sWAa Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2TjdnqlpwOjhijHCwHCP2d Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:15qtOgzv7mW3CQWQVgWIM6 Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1JWq0akDx4vcBvQ4fkPZ1A Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:61ZSShUVRGLfPVBPPrz7KU Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:4w13jgohpNDX1BmvYZNZpo Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5vEfvpU1O8cklC92W4ibzl Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3cWQ8dGRsZ9ticA70wArIc Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1eWLdoXYcQVuo82rsQ9wPJ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0X1sqQ652p1sceKM2nJlIJ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:75Cc2encvSkQ5OnDgAfJaV Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5eMjcHVRRh1tbimTgJPoGn Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5N6GzBkdnHcaH96R6aJQHm Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0yo6lVHmhSRLW0dWopkcRv Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3fbnbn6A5O5RNb08tlUEgd Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:7a90gal9tr3yS8bEXUvu8D Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:73QRIde4ZDLRTy20VYovkl Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:3pS9wLRtTwXkL9Uu93sBeW Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0J8A1ZVyJcRZxFNvtp6jRh Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2No8m5PPpazNUTuxbNYlVk Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0vyQzGo4ZhrOvp0zMkpyLP Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5iXdDUmuxBVQwECctRzn5z Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:74BiRtgDe2YPETdTCcsXoG Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0qprlw0jfsW4H9cG0FFE0Z Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1oG2vWELiGjIqxwqGcyqwF Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6NTqBHONQqmud0ONBzsLfZ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:6wWlaSxOrBizAcpqkuv1FJ Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:1SqGaM1N6OUvp5GUfx7zAb Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:2yfJBIeDD4Mcq6zl8r986Y Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5nekfiTN45vlxG0eNJQQye Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0E8zloWYA3Li81CJqFa9fI Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5jYYumalS1qELrcChOCZrK Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:5gOd6zDC8vhlYjqbQdJVWP Aug 26 12:14:23 volumio volumio[1240]: info: Preloading song: spotify:track:0wCrg1LhgPcGMw51qqpI6k Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:0CnD7Hiw6pae6rLsZw5q5W in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:6BPDPcnbDMDf58srVzbfX9 in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:3bNv3VuUOKgrf5hu3YcuRo in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:3EY1sjf2Xq86pQN0L4hEnU in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:2rbDhOo9Fh61Bbu23T2qCk in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:00YfGEWo5ebpbue5cQmtuR in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:1ZNcJrNdX4HbO6NGj8tlaL in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:1iEr3OkczMbk557RgIMtxm in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:0mEBHoIHl8UOW0jWMIwmAw in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:6E8nJ1vXBkbGm9m1oWdneC in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:19vLbTpnjkQtcCTaHVPQUm in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:5SEKw7xbTk21uulHDgPnhR in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:5surqTVBdeN36V9WZSjkWE in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:7AjYzHBfYycWYSloHJf7i2 in service spop Aug 26 12:14:23 volumio volumio[1240]: info: Exploding uri spotify:track:6Fc5TJKT60jBX4JhqVBCFY in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:6qCGPzPxysw0TdF3y3wqY8 in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:6QGJ6M3Mubu8r8QtHVK5kb in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:1L5UD1mNJi7Od9tJDzDCx9 in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:4Ul8dYosUhI8kTJWluukpv in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:4i8D5dh2n2B1mUWgQSC5ZO in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:1ZBD3JaXuUuA4bgpIi7jwG in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:5ehXkLVhU8uDyoKpSwgvBH in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:2HpfAl9P2pqVBRez532tF6 in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:7ySzRATA20XTnmk7CVXOTV in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:7c5eOfolAGuVwEaulxdvNJ in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:6vZZtivPrtpggT5SSXsYTy in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:1QEEqeFIZktqIpPI4jSVSF in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:4xh7W7tlNMIczFhupCPniY in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:6DMOzL2zPZBAW0LDYCYLWm in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:4ggQZbM68glGfoH1kImIwO in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:7IPMUKKlLQEBuKVhNzwTR1 in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:7m4rsekmwWrti3eg7l2RO4 in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:3TW48DvQ7ChwY1Yy1tkMaP in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:0NI9wGowTO80hVs3l6wa0i in service spop Aug 26 12:14:24 volumio volumio[1240]: info: Exploding uri spotify:track:6BD1X1PeV5UzYUdiVaD2yL in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:0jyElqUm59deyol951jPsr in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:2pSUpHpEOWgy0YJZO0tP5D in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:34hTNaJabBsvep2uCmfl9X in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:18UC721duMMiQRaWhyB8eU in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:6VaM0Vtj0tgtDTvJgVu2yy in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:3qGl6bfdUNjgDG3fvHZVAu in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:3OwtuzRo8mc7lgsBau9MWk in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:6wKqeNPpoLsximg7fRHasF in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:5GxejRsquV2JEu9TNGmWc6 in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:1wwKY8ICsV9wiCFMpdhPrr in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:5y788ya4NvwhBznoDIcXwK in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:4WpmwEdc4RBos8W8icSSxW in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:0w3ijrVQ2Q4U0guO2WhKaE in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:142Cyvjn8CLONFRDTK7SGh in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:5VYB1J9aXyYxjE5Z8kXRgK in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:5ImoRAws2ni1qYWrLR7sqi in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:0a0In4Eo07yoETGNey6Ue4 in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:4hpksgtpDgspt9UtkDnCMS in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:3NLrRZoMF0Lx6zTlYqeIo4 in service spop Aug 26 12:14:25 volumio volumio[1240]: info: Exploding uri spotify:track:5MxNLUsfh7uzROypsoO5qe in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:3nngQ14mffvvcoqkcJ5FET in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:1SfdzhXyrw84Hs0oMLaeEC in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:7ofV2J7Ndzo2s5NBEgfpxl in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:4BfbGIR6urmMtOaCXv2pCT in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:18I9sjv8BJpSSNDtlOdGrL in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:2YGLD3hQFE2f95vc1LgMlz in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:6l6b7epmL1Eg7ZlxYqMVed in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:6y5DbHoQMeqLVrWRhRMN7X in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:2GxGYGrixbXPFojONbzdo9 in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:1AWSemPzuGu4A9lVhSsFWJ in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:37ULGsYVNJmXxCVup7rTTu in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:4aYLAF6ckQ5ooGGGM7sWAa in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:2TjdnqlpwOjhijHCwHCP2d in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:15qtOgzv7mW3CQWQVgWIM6 in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:1JWq0akDx4vcBvQ4fkPZ1A in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:61ZSShUVRGLfPVBPPrz7KU in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:4w13jgohpNDX1BmvYZNZpo in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:5vEfvpU1O8cklC92W4ibzl in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:3cWQ8dGRsZ9ticA70wArIc in service spop Aug 26 12:14:26 volumio volumio[1240]: info: Exploding uri spotify:track:1eWLdoXYcQVuo82rsQ9wPJ in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:0X1sqQ652p1sceKM2nJlIJ in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:75Cc2encvSkQ5OnDgAfJaV in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:5eMjcHVRRh1tbimTgJPoGn in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:5N6GzBkdnHcaH96R6aJQHm in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:0yo6lVHmhSRLW0dWopkcRv in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:3fbnbn6A5O5RNb08tlUEgd in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:7a90gal9tr3yS8bEXUvu8D in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:73QRIde4ZDLRTy20VYovkl in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:3pS9wLRtTwXkL9Uu93sBeW in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:0J8A1ZVyJcRZxFNvtp6jRh in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:2No8m5PPpazNUTuxbNYlVk in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:0vyQzGo4ZhrOvp0zMkpyLP in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:5iXdDUmuxBVQwECctRzn5z in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:74BiRtgDe2YPETdTCcsXoG in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:0qprlw0jfsW4H9cG0FFE0Z in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:1oG2vWELiGjIqxwqGcyqwF in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:6NTqBHONQqmud0ONBzsLfZ in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:6wWlaSxOrBizAcpqkuv1FJ in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:1SqGaM1N6OUvp5GUfx7zAb in service spop Aug 26 12:14:27 volumio volumio[1240]: info: Exploding uri spotify:track:2yfJBIeDD4Mcq6zl8r986Y in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5nekfiTN45vlxG0eNJQQye in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0E8zloWYA3Li81CJqFa9fI in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5jYYumalS1qELrcChOCZrK in service spop Aug 26 12:14:28 volumio volumio[1240]: warn: Spotify API method getTrack failed due to "Too many requests". Stop all API requests for 10000 milliseconds. Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5gOd6zDC8vhlYjqbQdJVWP in service spop Aug 26 12:14:28 volumio volumio[1240]: warn: Spotify API method getTrack failed due to "Too many requests". Stop all API requests for 10000 milliseconds. Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0wCrg1LhgPcGMw51qqpI6k in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::ClearQueue Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::stop Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::stPlaybackTimer Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:28 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:28 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::serviceStop Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::serviceStop Aug 26 12:14:28 volumio volumio[1240]: info: [1787739268452] ControllerWebradio::stop Aug 26 12:14:28 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::clearPlayQueue Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::saveQueue Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Aug 26 12:14:28 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0CnD7Hiw6pae6rLsZw5q5W Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0CnD7Hiw6pae6rLsZw5q5W Aug 26 12:14:28 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:28.459+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=0 volume=80 Aug 26 12:14:28 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:28.459+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=https://live9.avf.ch/ipmusicslow320 title="IP music SLOW" Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::saveQueue Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::play index 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::addQueueItems Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::addQueueItems Aug 26 12:14:28 volumio volumio[1240]: info: Preload queue cleared Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6BPDPcnbDMDf58srVzbfX9 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6BPDPcnbDMDf58srVzbfX9 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3bNv3VuUOKgrf5hu3YcuRo Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3bNv3VuUOKgrf5hu3YcuRo Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3EY1sjf2Xq86pQN0L4hEnU Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3EY1sjf2Xq86pQN0L4hEnU Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2rbDhOo9Fh61Bbu23T2qCk Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2rbDhOo9Fh61Bbu23T2qCk Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:00YfGEWo5ebpbue5cQmtuR Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:00YfGEWo5ebpbue5cQmtuR Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1ZNcJrNdX4HbO6NGj8tlaL Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1ZNcJrNdX4HbO6NGj8tlaL Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1iEr3OkczMbk557RgIMtxm Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1iEr3OkczMbk557RgIMtxm Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0mEBHoIHl8UOW0jWMIwmAw Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0mEBHoIHl8UOW0jWMIwmAw Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6E8nJ1vXBkbGm9m1oWdneC Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6E8nJ1vXBkbGm9m1oWdneC Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:19vLbTpnjkQtcCTaHVPQUm Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:19vLbTpnjkQtcCTaHVPQUm Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5SEKw7xbTk21uulHDgPnhR Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5SEKw7xbTk21uulHDgPnhR Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5surqTVBdeN36V9WZSjkWE Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5surqTVBdeN36V9WZSjkWE Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7AjYzHBfYycWYSloHJf7i2 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7AjYzHBfYycWYSloHJf7i2 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6Fc5TJKT60jBX4JhqVBCFY Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6Fc5TJKT60jBX4JhqVBCFY Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6qCGPzPxysw0TdF3y3wqY8 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6qCGPzPxysw0TdF3y3wqY8 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6QGJ6M3Mubu8r8QtHVK5kb Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6QGJ6M3Mubu8r8QtHVK5kb Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1L5UD1mNJi7Od9tJDzDCx9 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1L5UD1mNJi7Od9tJDzDCx9 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4Ul8dYosUhI8kTJWluukpv Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4Ul8dYosUhI8kTJWluukpv Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4i8D5dh2n2B1mUWgQSC5ZO Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4i8D5dh2n2B1mUWgQSC5ZO Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1ZBD3JaXuUuA4bgpIi7jwG Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1ZBD3JaXuUuA4bgpIi7jwG Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5ehXkLVhU8uDyoKpSwgvBH Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5ehXkLVhU8uDyoKpSwgvBH Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2HpfAl9P2pqVBRez532tF6 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2HpfAl9P2pqVBRez532tF6 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7ySzRATA20XTnmk7CVXOTV Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7ySzRATA20XTnmk7CVXOTV Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7c5eOfolAGuVwEaulxdvNJ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7c5eOfolAGuVwEaulxdvNJ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6vZZtivPrtpggT5SSXsYTy Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6vZZtivPrtpggT5SSXsYTy Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4xh7W7tlNMIczFhupCPniY Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4xh7W7tlNMIczFhupCPniY Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6DMOzL2zPZBAW0LDYCYLWm Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6DMOzL2zPZBAW0LDYCYLWm Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4ggQZbM68glGfoH1kImIwO Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4ggQZbM68glGfoH1kImIwO Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7IPMUKKlLQEBuKVhNzwTR1 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7IPMUKKlLQEBuKVhNzwTR1 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7m4rsekmwWrti3eg7l2RO4 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7m4rsekmwWrti3eg7l2RO4 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3TW48DvQ7ChwY1Yy1tkMaP Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3TW48DvQ7ChwY1Yy1tkMaP Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0NI9wGowTO80hVs3l6wa0i Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0NI9wGowTO80hVs3l6wa0i Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6BD1X1PeV5UzYUdiVaD2yL Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6BD1X1PeV5UzYUdiVaD2yL Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0jyElqUm59deyol951jPsr Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0jyElqUm59deyol951jPsr Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2pSUpHpEOWgy0YJZO0tP5D Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2pSUpHpEOWgy0YJZO0tP5D Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:34hTNaJabBsvep2uCmfl9X Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:34hTNaJabBsvep2uCmfl9X Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:18UC721duMMiQRaWhyB8eU Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:18UC721duMMiQRaWhyB8eU Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6VaM0Vtj0tgtDTvJgVu2yy Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6VaM0Vtj0tgtDTvJgVu2yy Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3qGl6bfdUNjgDG3fvHZVAu Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3qGl6bfdUNjgDG3fvHZVAu Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3OwtuzRo8mc7lgsBau9MWk Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3OwtuzRo8mc7lgsBau9MWk Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6wKqeNPpoLsximg7fRHasF Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6wKqeNPpoLsximg7fRHasF Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5GxejRsquV2JEu9TNGmWc6 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5GxejRsquV2JEu9TNGmWc6 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1wwKY8ICsV9wiCFMpdhPrr Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1wwKY8ICsV9wiCFMpdhPrr Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5y788ya4NvwhBznoDIcXwK Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5y788ya4NvwhBznoDIcXwK Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4WpmwEdc4RBos8W8icSSxW Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4WpmwEdc4RBos8W8icSSxW Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0w3ijrVQ2Q4U0guO2WhKaE Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0w3ijrVQ2Q4U0guO2WhKaE Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:142Cyvjn8CLONFRDTK7SGh Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:142Cyvjn8CLONFRDTK7SGh Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5VYB1J9aXyYxjE5Z8kXRgK Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5VYB1J9aXyYxjE5Z8kXRgK Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5ImoRAws2ni1qYWrLR7sqi Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5ImoRAws2ni1qYWrLR7sqi Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0a0In4Eo07yoETGNey6Ue4 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0a0In4Eo07yoETGNey6Ue4 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4hpksgtpDgspt9UtkDnCMS Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4hpksgtpDgspt9UtkDnCMS Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3NLrRZoMF0Lx6zTlYqeIo4 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3NLrRZoMF0Lx6zTlYqeIo4 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5MxNLUsfh7uzROypsoO5qe Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5MxNLUsfh7uzROypsoO5qe Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3nngQ14mffvvcoqkcJ5FET Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3nngQ14mffvvcoqkcJ5FET Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1SfdzhXyrw84Hs0oMLaeEC Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1SfdzhXyrw84Hs0oMLaeEC Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7ofV2J7Ndzo2s5NBEgfpxl Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7ofV2J7Ndzo2s5NBEgfpxl Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4BfbGIR6urmMtOaCXv2pCT Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4BfbGIR6urmMtOaCXv2pCT Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:18I9sjv8BJpSSNDtlOdGrL Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:18I9sjv8BJpSSNDtlOdGrL Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2YGLD3hQFE2f95vc1LgMlz Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2YGLD3hQFE2f95vc1LgMlz Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6l6b7epmL1Eg7ZlxYqMVed Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6l6b7epmL1Eg7ZlxYqMVed Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6y5DbHoQMeqLVrWRhRMN7X Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6y5DbHoQMeqLVrWRhRMN7X Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2GxGYGrixbXPFojONbzdo9 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2GxGYGrixbXPFojONbzdo9 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1AWSemPzuGu4A9lVhSsFWJ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1AWSemPzuGu4A9lVhSsFWJ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:37ULGsYVNJmXxCVup7rTTu Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:37ULGsYVNJmXxCVup7rTTu Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4aYLAF6ckQ5ooGGGM7sWAa Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4aYLAF6ckQ5ooGGGM7sWAa Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2TjdnqlpwOjhijHCwHCP2d Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2TjdnqlpwOjhijHCwHCP2d Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:15qtOgzv7mW3CQWQVgWIM6 Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:15qtOgzv7mW3CQWQVgWIM6 Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1JWq0akDx4vcBvQ4fkPZ1A Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1JWq0akDx4vcBvQ4fkPZ1A Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:61ZSShUVRGLfPVBPPrz7KU Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:61ZSShUVRGLfPVBPPrz7KU Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4w13jgohpNDX1BmvYZNZpo Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:4w13jgohpNDX1BmvYZNZpo Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5vEfvpU1O8cklC92W4ibzl Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5vEfvpU1O8cklC92W4ibzl Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3cWQ8dGRsZ9ticA70wArIc Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3cWQ8dGRsZ9ticA70wArIc Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1eWLdoXYcQVuo82rsQ9wPJ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1eWLdoXYcQVuo82rsQ9wPJ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0X1sqQ652p1sceKM2nJlIJ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0X1sqQ652p1sceKM2nJlIJ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:75Cc2encvSkQ5OnDgAfJaV Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:75Cc2encvSkQ5OnDgAfJaV Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5eMjcHVRRh1tbimTgJPoGn Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5eMjcHVRRh1tbimTgJPoGn Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5N6GzBkdnHcaH96R6aJQHm Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5N6GzBkdnHcaH96R6aJQHm Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0yo6lVHmhSRLW0dWopkcRv Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0yo6lVHmhSRLW0dWopkcRv Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3fbnbn6A5O5RNb08tlUEgd Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3fbnbn6A5O5RNb08tlUEgd Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7a90gal9tr3yS8bEXUvu8D Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:7a90gal9tr3yS8bEXUvu8D Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:73QRIde4ZDLRTy20VYovkl Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:73QRIde4ZDLRTy20VYovkl Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3pS9wLRtTwXkL9Uu93sBeW Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:3pS9wLRtTwXkL9Uu93sBeW Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0J8A1ZVyJcRZxFNvtp6jRh Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0J8A1ZVyJcRZxFNvtp6jRh Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2No8m5PPpazNUTuxbNYlVk Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2No8m5PPpazNUTuxbNYlVk Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0vyQzGo4ZhrOvp0zMkpyLP Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0vyQzGo4ZhrOvp0zMkpyLP Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5iXdDUmuxBVQwECctRzn5z Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5iXdDUmuxBVQwECctRzn5z Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:74BiRtgDe2YPETdTCcsXoG Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:74BiRtgDe2YPETdTCcsXoG Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0qprlw0jfsW4H9cG0FFE0Z Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:0qprlw0jfsW4H9cG0FFE0Z Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1oG2vWELiGjIqxwqGcyqwF Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1oG2vWELiGjIqxwqGcyqwF Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6NTqBHONQqmud0ONBzsLfZ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6NTqBHONQqmud0ONBzsLfZ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6wWlaSxOrBizAcpqkuv1FJ Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:6wWlaSxOrBizAcpqkuv1FJ Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1SqGaM1N6OUvp5GUfx7zAb Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:1SqGaM1N6OUvp5GUfx7zAb Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2yfJBIeDD4Mcq6zl8r986Y Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:2yfJBIeDD4Mcq6zl8r986Y Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5nekfiTN45vlxG0eNJQQye Aug 26 12:14:28 volumio volumio[1240]: info: Using cached record of: spotify:track:5nekfiTN45vlxG0eNJQQye Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0E8zloWYA3Li81CJqFa9fI Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0E8zloWYA3Li81CJqFa9fI in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5jYYumalS1qELrcChOCZrK Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5jYYumalS1qELrcChOCZrK in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5gOd6zDC8vhlYjqbQdJVWP Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5gOd6zDC8vhlYjqbQdJVWP in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0wCrg1LhgPcGMw51qqpI6k Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0wCrg1LhgPcGMw51qqpI6k in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6bzwsAtj4TqaBysLDrGiO3 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:6bzwsAtj4TqaBysLDrGiO3 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0f9h8awV1X4jSllHXXYdfX Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0f9h8awV1X4jSllHXXYdfX in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:389QX9Q1eUOEZ19vtzzI9O Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:389QX9Q1eUOEZ19vtzzI9O in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5HNCy40Ni5BZJFw1TKzRsC Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5HNCy40Ni5BZJFw1TKzRsC in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5Yp3e8RcjYevBTZYJ7OD7s Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5Yp3e8RcjYevBTZYJ7OD7s in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1tZdrScspGPI4cdkSChrcX Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:1tZdrScspGPI4cdkSChrcX in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5AW06u82yN9agtQlINqny5 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5AW06u82yN9agtQlINqny5 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:50dcEB31QnSdy7CyL7VrX7 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:50dcEB31QnSdy7CyL7VrX7 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3yOdfq8eIpBrotqjL5gfdh Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3yOdfq8eIpBrotqjL5gfdh in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7gUMShP1l20tC0xf17Zplk Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:7gUMShP1l20tC0xf17Zplk in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0pIrZLoXAuTfnMGjhVNxe3 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0pIrZLoXAuTfnMGjhVNxe3 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6JqxWJYcxbePmG7nQFVb79 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:6JqxWJYcxbePmG7nQFVb79 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0lzpfrTARexLFXEACKSXTh Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0lzpfrTARexLFXEACKSXTh in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7s5YLzLZvWffXgsd6JCZ0H Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:7s5YLzLZvWffXgsd6JCZ0H in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3XC7Jd6SfrQYKZJ6inyRHK in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5sUrZEf4qEtjepdgcetith Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5sUrZEf4qEtjepdgcetith in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5ou2BiQ9FxIYkxsYvYHpAT Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5ou2BiQ9FxIYkxsYvYHpAT in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:78cfErmFWu8gWqe9XG90c2 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:78cfErmFWu8gWqe9XG90c2 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2X59ZxwE9x4pWnxsxKhr1E Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:2X59ZxwE9x4pWnxsxKhr1E in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6vcNJUWtUPB0hKC6VbZriY Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:6vcNJUWtUPB0hKC6VbZriY in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:64gpgh5jBMxyh7iVJll23t Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:64gpgh5jBMxyh7iVJll23t in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2LawezPeJhN4AWuSB0GtAU Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:2LawezPeJhN4AWuSB0GtAU in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5V1AHQugSTASVez5ffJtFo Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5V1AHQugSTASVez5ffJtFo in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7ulkhAxc7qtCxlJA8ZMXBm Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:7ulkhAxc7qtCxlJA8ZMXBm in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7BsD3xcEhgTmc4zegwbkHj Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:7BsD3xcEhgTmc4zegwbkHj in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3MrRksHupTVEQ7YbA0FsZK Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3MrRksHupTVEQ7YbA0FsZK in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3FmAUR4SPWa3P1KyDf21Fu Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3FmAUR4SPWa3P1KyDf21Fu in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:1Kvjmr2oqZjzhryoEKzqij Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:1Kvjmr2oqZjzhryoEKzqij in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4MhTFsyqIJnjsOweVcU8ug Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:4MhTFsyqIJnjsOweVcU8ug in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5XE8c07vbpQBUriwybOHtd Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5XE8c07vbpQBUriwybOHtd in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:4gpext9x0CbdD9NWaa4nDj Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:4gpext9x0CbdD9NWaa4nDj in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3Ib3SJV8ZXuLvDiDb2w1As Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3Ib3SJV8ZXuLvDiDb2w1As in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:51pQ7vY7WXzxskwloaeqyj Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:51pQ7vY7WXzxskwloaeqyj in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:5Ws2EQtBiwpAcBYYSB14ns Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:5Ws2EQtBiwpAcBYYSB14ns in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:40riOy7x9W7GXjyGp4pjAv Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:40riOy7x9W7GXjyGp4pjAv in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0QxCLD4xJlE8vv0Cvrb8TO Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0QxCLD4xJlE8vv0Cvrb8TO in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0KMGxYKeUzK9wc5DZCt3HT Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0KMGxYKeUzK9wc5DZCt3HT in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:74EV0g12ihUoOUXMprFpZB Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:74EV0g12ihUoOUXMprFpZB in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:0c1gHntWjKD7QShC8s99sq Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:0c1gHntWjKD7QShC8s99sq in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:75XSmc3qMTJ9fC70uklGMF Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:75XSmc3qMTJ9fC70uklGMF in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:7o10Pto0YFchZl1zL4dLPH Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:7o10Pto0YFchZl1zL4dLPH in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:29wJiSQcze8HEmxkUQykyp Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:29wJiSQcze8HEmxkUQykyp in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:612VcBshQcy4mpB2utGc3H Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:612VcBshQcy4mpB2utGc3H in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6oD0TvHIYmObKNl5Mz05ri Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:6oD0TvHIYmObKNl5Mz05ri in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:2N2yrmodOnVF10mKvItC9P Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:2N2yrmodOnVF10mKvItC9P in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3ZFwuJwUpIl0GeXsvF1ELf Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3ZFwuJwUpIl0GeXsvF1ELf in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:3dubLey4QOmUjJCyiGPEMo Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:3dubLey4QOmUjJCyiGPEMo in service spop Aug 26 12:14:28 volumio volumio[1240]: info: Adding Item to queue: spotify:track:6GXl3ADX2Lw5JaOSk620j2 Aug 26 12:14:28 volumio volumio[1240]: info: Exploding uri spotify:track:6GXl3ADX2Lw5JaOSk620j2 in service spop Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::stop Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::play index undefined Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 12:14:28 volumio volumio[1240]: info: [1787739268512] ControllerSpotify::clearAddPlayTrack Aug 26 12:14:28 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/play Aug 26 12:14:28 volumio volumio[1240]: info: FusionDsp - Volumio is not playing Aug 26 12:14:28 volumio volumio[1240]: info: FusionDsp - Clipped samples monitor stopped Aug 26 12:14:28 volumio volumio[1240]: info: Aug 26 12:14:28 volumio volumio[1240]: ---------------------------- MPD announces state update: player Aug 26 12:14:28 volumio volumio[1240]: info: sendMpdCommand stop took 80 milliseconds Aug 26 12:14:28 volumio volumio[1240]: info: ControllerMpd::getState Aug 26 12:14:28 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand status Aug 26 12:14:28 volumio volumio[1240]: info: sendMpdCommand status took 2 milliseconds Aug 26 12:14:28 volumio volumio[1240]: verbose: ControllerMpd::parseState Aug 26 12:14:28 volumio volumio[1240]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 12:14:28 volumio volumio[1240]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 12:14:28 volumio volumio[1240]: verbose: ControllerMpd::parseTrackInfo Aug 26 12:14:28 volumio volumio[1240]: info: ControllerMpd::pushState Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::servicePushState Aug 26 12:14:28 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:28 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:28 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:28 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:28 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:28 volumio volumio[1240]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Aug 26 12:14:28 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:28.553+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=0 volume=80 Aug 26 12:14:28 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:28.555+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:28 volumio volumio[1240]: info: ------------------------------ 26ms Aug 26 12:14:29 volumio volumio[1240]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 26 12:14:31 volumio bluealsa[966]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_04_F0_EE_80_98_D1, ...) Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:31 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Aug 26 12:14:31 volumio volumio[1240]: info: CoreStateMachine::play index undefined Aug 26 12:14:31 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:31 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:31 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Aug 26 12:14:31 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 12:14:31 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 12:14:31 volumio volumio[1240]: info: [1787739271919] ControllerSpotify::clearAddPlayTrack Aug 26 12:14:31 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/play Aug 26 12:14:35 volumio bluealsa[966]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_04_F0_EE_80_98_D1, ...) Aug 26 12:14:39 volumio volumio[1240]: info: CoreCommandRouter::volumioPushQueue Aug 26 12:14:39 volumio volumio[1240]: info: CorePlayQueue::saveQueue Aug 26 12:14:39 volumio volumio[1240]: info: CoreStateMachine::updateTrackBlock Aug 26 12:14:39 volumio volumio[1240]: info: CorePlayQueue::getTrackBlock Aug 26 12:14:41 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:41.984+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-731.888028ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.043+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.893+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=http://pushupdates.volumio.org duration=850.1554ms Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.897+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=853.371542ms Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.898+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=854.871985ms Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.909+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=865.602579ms Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.982+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://google.com duration=938.011358ms Aug 26 12:14:42 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:42.986+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://www.googleapis.com duration=942.185504ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.009+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=965.542114ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.016+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://database.volumio.cloud duration=972.962069ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.018+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://functions.volumio.cloud duration=975.332296ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.019+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://securetoken.googleapis.com duration=974.292328ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.039+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=https://functions.volumio.cloud duration=995.45356ms Aug 26 12:14:43 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:43.557+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=http://cddb.volumio.org duration=1.514802185s Aug 26 12:14:44 volumio sudo[5143]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 12:14:44 volumio sudo[5143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 12:14:44 volumio sudo[5143]: pam_unix(sudo:session): session closed for user root Aug 26 12:14:44 volumio sudo[5145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 12:14:44 volumio sudo[5145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 12:14:44 volumio sudo[5145]: pam_unix(sudo:session): session closed for user root Aug 26 12:14:44 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:44.817+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" latency=-733.051671ms timeout=10s endpoint=http://plugins.volumio.org duration=2.773538601s Aug 26 12:14:44 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.197 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7 Build/CP2A.260705.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 12:14:45 volumio volumio[1240]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:45 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 12:14:45 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 12:14:45 volumio sudo[5149]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 12:14:45 volumio sudo[5149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 12:14:45 volumio sudo[5149]: pam_unix(sudo:session): session closed for user root Aug 26 12:14:45 volumio sudo[5151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 12:14:45 volumio sudo[5151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 12:14:45 volumio sudo[5151]: pam_unix(sudo:session): session closed for user root Aug 26 12:14:45 volumio volumio[1240]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.197 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7 Build/CP2A.260705.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 26 12:14:46 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:46 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:47 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 12:14:47 volumio volumio[1240]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 12:14:47 volumio volumio[1240]: info: Listing playlists Aug 26 12:14:47 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 12:14:47 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 12:14:47 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 12:14:47 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 12:14:49 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:14:49 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:49 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 26 12:14:49 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Aug 26 12:14:49 volumio volumio[1240]: info: CoreStateMachine::play index undefined Aug 26 12:14:49 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:14:49 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:49 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Aug 26 12:14:49 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:49 volumio volumio[1240]: info: [1787739289148] ControllerSpotify::clearAddPlayTrack Aug 26 12:14:49 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/play Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume85 Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.536+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4254 volume=85 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.536+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume95 Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume90 Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume75 Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume60 Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.617+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4254 volume=60 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.617+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.620+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4254 volume=60 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.621+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.623+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4254 volume=60 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.624+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.628+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4254 volume=60 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.628+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 85 Aug 26 12:14:53 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 60 Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume60 Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.778+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4549 volume=60 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.778+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume80 Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.886+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4549 volume=80 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.886+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:53 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 80 Aug 26 12:14:53 volumio volumio[1240]: info: VolumeController::SetAlsaVolume95 Aug 26 12:14:53 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:53 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:53 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:53 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.995+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4799 volume=95 Aug 26 12:14:53 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:53.997+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 95 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume90 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.105+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=4799 volume=90 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.106+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 90 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume45 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.229+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5050 volume=45 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.229+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 45 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume25 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.475+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5301 volume=25 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.476+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 25 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume65 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.569+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5301 volume=65 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.571+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 65 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume100 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.685+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5301 volume=100 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.686+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 100 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume95 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.793+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5558 volume=95 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.794+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 95 Aug 26 12:14:54 volumio volumio[1240]: info: VolumeController::SetAlsaVolume60 Aug 26 12:14:54 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:54 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:54 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:54 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.897+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5558 volume=60 Aug 26 12:14:54 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:54.898+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:54 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 60 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume50 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.135+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5808 volume=50 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.136+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume85 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.166+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=5808 volume=85 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.166+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 50 Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 85 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume95 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.279+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6059 volume=95 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.280+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 95 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume60 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.405+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6059 volume=60 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.406+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 60 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume75 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.522+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6310 volume=75 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.522+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 75 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume80 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6310 volume=80 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 80 Aug 26 12:14:55 volumio volumio[1240]: info: VolumeController::SetAlsaVolume65 Aug 26 12:14:55 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:55 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:55 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:55 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.805+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6560 volume=65 Aug 26 12:14:55 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:55.806+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:55 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 65 Aug 26 12:14:56 volumio volumio[1240]: info: VolumeController::SetAlsaVolume70 Aug 26 12:14:56 volumio volumio[1240]: info: CoreStateMachine::pushState Aug 26 12:14:56 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:14:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 12:14:56 volumio volumio[1240]: info: CoreCommandRouter::volumioPushState Aug 26 12:14:56 volumio volumio[1240]: info: MRS: Pushing multiroomSync output update for this device Aug 26 12:14:56 volumio volumio[1240]: info: MRS: Pushing multiroomSync output Aug 26 12:14:56 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:56.075+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" state=STATUS_STOPPED positionMs=6811 volume=70 Aug 26 12:14:56 volumio volumio5-onboarding[1568]: time=2026-08-26T12:14:56.076+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.197:37432,00:00:00:00:00:00%01 @ 0x27629f0" id=spotify:track:0CnD7Hiw6pae6rLsZw5q5W title=Incomplete Aug 26 12:14:56 volumio volumio[1240]: info: Setting Spotify Volume from Volumio: 70 Aug 26 12:14:57 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/volume Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 12:15:03 volumio volumio[1240]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 26 12:15:03 volumio volumio[1240]: info: Received Get System Version Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 12:15:03 volumio volumio[1240]: info: Received Get System Info Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 12:15:03 volumio volumio[1240]: info: Discovery: Getting this device information Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:15:03 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:15:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 12:15:08 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 26 12:15:08 volumio volumio[1240]: info: Playing Remote Device: http://192.168.1.12 Aug 26 12:15:08 volumio volumio[1240]: info: Aug 26 12:15:08 volumio volumio[1240]: ---------------------------- Client requests Volumio play Aug 26 12:15:08 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Aug 26 12:15:08 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:15:08 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 26 12:15:08 volumio volumio[1240]: info: CoreCommandRouter::volumioPlay Aug 26 12:15:08 volumio volumio[1240]: info: CoreStateMachine::play index undefined Aug 26 12:15:08 volumio volumio[1240]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 12:15:08 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:15:08 volumio volumio[1240]: info: CoreStateMachine::startPlaybackTimer Aug 26 12:15:08 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Aug 26 12:15:08 volumio volumio[1240]: info: [1787739308505] ControllerSpotify::clearAddPlayTrack Aug 26 12:15:08 volumio volumio[1240]: info: Sending Spotify command with payload to local API: /player/play Aug 26 12:15:08 volumio volumio[1240]: info: Done playing: Aug 26 12:15:11 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Aug 26 12:15:11 volumio volumio[1240]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Aug 26 12:15:11 volumio volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 12:15:11 volumio volumio[1240]: TypeError: Cannot read properties of undefined (reading 'then') Aug 26 12:15:11 volumio volumio[1240]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Aug 26 12:15:11 volumio volumio[1240]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Aug 26 12:15:11 volumio volumio[1240]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Aug 26 12:15:11 volumio volumio[1240]: at Socket.emit (node:events:514:28) Aug 26 12:15:11 volumio volumio[1240]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 26 12:15:11 volumio volumio[1240]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 26 12:15:11 volumio volumio[1240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 12:15:11 volumio sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 12:14' Aug 26 12:15:11 volumio sudo[5241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"