-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-12-20 08:47:59 CET. -- Dec 20 08:46:02 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 20 08:46:02 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Dec 20 08:46:02 lucasplace volumio[998]: info: [soundcloud-browse] browseUri(soundcloud) Dec 20 08:46:04 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:04 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 20 08:46:10 lucasplace volumio[998]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 20 08:46:10 lucasplace volumio[998]: info: Received Get System Version Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 20 08:46:10 lucasplace volumio[998]: info: Received Get System Info Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 08:46:10 lucasplace volumio[998]: info: Discovery: Getting this device information Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:46:10 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:10 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 08:46:11 lucasplace volumio[998]: Searching plugin music_service/soundcloud Dec 20 08:46:11 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Dec 20 08:46:12 lucasplace volumio[998]: info: All search sources collected, pushing search results Dec 20 08:46:13 lucasplace volumio[998]: Searching plugin music_service/soundcloud Dec 20 08:46:13 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Dec 20 08:46:14 lucasplace volumio[998]: info: All search sources collected, pushing search results Dec 20 08:46:14 lucasplace go-librespot[1207]: time="2025-12-20T08:46:14+01:00" level=trace msg="sent dealer ping" Dec 20 08:46:14 lucasplace go-librespot[1207]: time="2025-12-20T08:46:14+01:00" level=trace msg="received dealer pong" Dec 20 08:46:14 lucasplace volumio[998]: Searching plugin music_service/soundcloud Dec 20 08:46:14 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Dec 20 08:46:16 lucasplace volumio[998]: info: All search sources collected, pushing search results Dec 20 08:46:17 lucasplace volumio[998]: Searching plugin music_service/soundcloud Dec 20 08:46:17 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Dec 20 08:46:18 lucasplace volumio[998]: info: All search sources collected, pushing search results Dec 20 08:46:19 lucasplace volumio[998]: Searching plugin music_service/soundcloud Dec 20 08:46:19 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Dec 20 08:46:19 lucasplace volumio[998]: info: All search sources collected, pushing search results Dec 20 08:46:23 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Dec 20 08:46:23 lucasplace volumio[998]: info: [soundcloud-browse] browseUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066) Dec 20 08:46:24 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 Dec 20 08:46:24 lucasplace volumio[998]: info: Preloading song: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203788 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203545 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203362 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203101 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233202669 Dec 20 08:46:24 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 in service soundcloud Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390) Dec 20 08:46:24 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233202390 Dec 20 08:46:27 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:27 lucasplace volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::ClearQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:27 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 Dec 20 08:46:27 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:27 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::play index 1 Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:27 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 Dec 20 08:46:27 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 Dec 20 08:46:27 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:27 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::getTrack 1 Dec 20 08:46:27 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:27 lucasplace volumio[998]: info: CorePlayQueue::getTrack 1 Dec 20 08:46:27 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203545 Dec 20 08:46:27 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:27 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:32 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:32 lucasplace volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::ClearQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:32 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203788 Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203545 Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203362 Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:32 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:32 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::play index 3 Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:32 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202669 Dec 20 08:46:32 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 Dec 20 08:46:32 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233202390 Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:32 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::getTrack 3 Dec 20 08:46:32 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:32 lucasplace volumio[998]: info: CorePlayQueue::getTrack 3 Dec 20 08:46:32 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203101 Dec 20 08:46:32 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:32 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:35 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::ClearQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:35 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:35 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:35 lucasplace volumio[998]: info: Using cached record of: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066/tracks@userId=1282310066@title=Tracks/track@trackId=2233203101 Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::play index 0 Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:35 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:35 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 08:46:35 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 08:46:35 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203101 Dec 20 08:46:35 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:35 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:44 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:44 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:44 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:44 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:44 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:44 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:44 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 08:46:44 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 08:46:44 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 08:46:44 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203101 Dec 20 08:46:44 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:44 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:44 lucasplace go-librespot[1207]: time="2025-12-20T08:46:44+01:00" level=trace msg="sent dealer ping" Dec 20 08:46:44 lucasplace go-librespot[1207]: time="2025-12-20T08:46:44+01:00" level=trace msg="received dealer pong" Dec 20 08:46:44 lucasplace go-librespot[1207]: time="2025-12-20T08:46:44+01:00" level=trace msg="received accesspoint ping" Dec 20 08:46:44 lucasplace go-librespot[1207]: time="2025-12-20T08:46:44+01:00" level=trace msg="received accesspoint pong ack" Dec 20 08:46:45 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:45 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:45 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:45 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:45 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:45 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:45 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetVisibleSources Dec 20 08:46:45 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 20 08:46:45 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 20 08:46:45 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203101 Dec 20 08:46:45 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:45 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:46 lucasplace volumio[998]: info: Listing playlists Dec 20 08:46:49 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::ClearQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::clearPlayQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::addQueueItems Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::addQueueItems Dec 20 08:46:49 lucasplace volumio[998]: info: Preload queue cleared Dec 20 08:46:49 lucasplace volumio[998]: info: Adding Item to queue: soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066 Dec 20 08:46:49 lucasplace volumio[998]: info: Exploding uri soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066 in service soundcloud Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-browse] explodeUri(soundcloud/users@search=Digital_trip@title=SoundCloud%20users%20matching%20'Digital_trip'/users@userId=1282310066) Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203788 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203545 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203362 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233203101 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233202669 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-explodable] getTrackUri(): soundcloud/track@trackId=2233202390 Dec 20 08:46:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::saveQueue Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::updateTrackBlock Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::getTrackBlock Dec 20 08:46:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::play index 0 Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:49 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:49 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:49 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203788 Dec 20 08:46:49 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:49 lucasplace volumio[998]: error: No transcoding found Dec 20 08:46:54 lucasplace volumio[998]: info: CoreCommandRouter::volumioPlay Dec 20 08:46:54 lucasplace volumio[998]: info: CoreStateMachine::play index undefined Dec 20 08:46:54 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:46:54 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:54 lucasplace volumio[998]: info: CoreStateMachine::startPlaybackTimer Dec 20 08:46:54 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:46:54 lucasplace volumio[998]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=2233203788 Dec 20 08:46:54 lucasplace volumio[998]: error: [soundcloud-play] clearAddPlayTrack() error Dec 20 08:46:54 lucasplace volumio[998]: error: No transcoding found Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:00 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:00 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:00 lucasplace volumio[998]: info: Currently active: soundcloud Dec 20 08:47:00 lucasplace volumio[998]: info: Stopping currently active service Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioStop Dec 20 08:47:00 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:47:00 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:47:00 lucasplace volumio[998]: info: TidalConnect Active Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:00 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:00 lucasplace volumio[998]: info: Currently active: soundcloud Dec 20 08:47:00 lucasplace volumio[998]: info: Setting Volatile state to tidalconnect Dec 20 08:47:00 lucasplace volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:00 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:00 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:00 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:00 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:00 lucasplace volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received tidalconnect Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:00 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:00 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:00 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:00 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:00 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:00 lucasplace volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received tidalconnect Dec 20 08:47:00 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:00 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:00 lucasplace volumio[998]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.4Qu9rOaryTcYTmZuQB6nv1D1c2C2.938b62d965d3e56b3c67a14d56892407.state.status' Dec 20 08:47:01 lucasplace vtcs[1482]: [2025-12-20 08:47:01.078] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Dec 20 08:47:01 lucasplace volumio[998]: info: Pushing metadata Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:01 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:01 lucasplace volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received tidalconnect Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:01 lucasplace volumio[998]: info: CorePlayQueue::getTrack 0 Dec 20 08:47:01 lucasplace volumio[998]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received tidalconnect Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:01 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:01 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:01 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:01 lucasplace volumio[998]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.4Qu9rOaryTcYTmZuQB6nv1D1c2C2.938b62d965d3e56b3c67a14d56892407.state.status' Dec 20 08:47:01 lucasplace volumio[998]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.4Qu9rOaryTcYTmZuQB6nv1D1c2C2.938b62d965d3e56b3c67a14d56892407.state.status' Dec 20 08:47:01 lucasplace volumio[998]: info: Pushing metadata Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:01 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:01 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:01 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:01 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:02 lucasplace vtcs[1482]: [close:85] Entering Dec 20 08:47:02 lucasplace vtcs[1482]: [close:100] Exiting Dec 20 08:47:02 lucasplace vtcs[1482]: [open:50] Opening with sample rate: 44100, type: 0 Dec 20 08:47:02 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:02 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:02 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:02 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:02 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:02 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:02 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:02 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:03 lucasplace vtcs[1482]: [2025-12-20 08:47:03] [error] handle_read_frame error: asio.ssl.stream:1 (stream truncated) Dec 20 08:47:03 lucasplace vtcs[1482]: [2025-12-20 08:47:03] [info] asio async_shutdown error: asio.ssl.stream:1 (stream truncated) Dec 20 08:47:04 lucasplace volumio[998]: info: Pushing metadata Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:04 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:04 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:04 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:04 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:04 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:04 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:04 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:04 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:04 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:06 lucasplace vtcs[1482]: [start:106] Entering Dec 20 08:47:06 lucasplace vtcs[1482]: [start:113] Reopening PCM device... Dec 20 08:47:06 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:06 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:06 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:06 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:06 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:06 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:06 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:06 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:06 lucasplace vtcs[1482]: [start:115] PCM opened successfully. Dec 20 08:47:06 lucasplace vtcs[1482]: [start:120] Exiting Dec 20 08:47:06 lucasplace vtcs[1482]: [feedThread:223] Entering Dec 20 08:47:07 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:07 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:07 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:07 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:07 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:07 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:07 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:07 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:08 lucasplace vtcs[1482]: [pause:147] Entering Dec 20 08:47:08 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:08 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:08 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:08 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:08 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:08 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:08 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:08 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:08 lucasplace vtcs[1482]: [feedThread:276] Exiting Dec 20 08:47:08 lucasplace vtcs[1482]: [pause:161] Exiting Dec 20 08:47:14 lucasplace go-librespot[1207]: time="2025-12-20T08:47:14+01:00" level=trace msg="sent dealer ping" Dec 20 08:47:14 lucasplace go-librespot[1207]: time="2025-12-20T08:47:14+01:00" level=trace msg="received dealer pong" Dec 20 08:47:44 lucasplace go-librespot[1207]: time="2025-12-20T08:47:44+01:00" level=trace msg="sent dealer ping" Dec 20 08:47:44 lucasplace go-librespot[1207]: time="2025-12-20T08:47:44+01:00" level=trace msg="received dealer pong" Dec 20 08:47:48 lucasplace vtcs[1482]: [close:85] Entering Dec 20 08:47:48 lucasplace vtcs[1482]: [close:100] Exiting Dec 20 08:47:48 lucasplace vtcs[1482]: [2025-12-20 08:47:48.959] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:48 lucasplace volumio[998]: info: Pushing metadata Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:48 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:48 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace vtcs[1482]: [close:85] Entering Dec 20 08:47:49 lucasplace vtcs[1482]: [close:100] Exiting Dec 20 08:47:49 lucasplace volumio[998]: info: Pushing metadata Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace vtcs[1482]: [close:85] Entering Dec 20 08:47:49 lucasplace vtcs[1482]: [close:100] Exiting Dec 20 08:47:49 lucasplace vtcs[1482]: [open:50] Opening with sample rate: 44100, type: 0 Dec 20 08:47:49 lucasplace vtcs[1482]: [start:106] Entering Dec 20 08:47:49 lucasplace vtcs[1482]: [start:113] Reopening PCM device... Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace vtcs[1482]: [start:115] PCM opened successfully. Dec 20 08:47:49 lucasplace vtcs[1482]: [start:120] Exiting Dec 20 08:47:49 lucasplace vtcs[1482]: [feedThread:223] Entering Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:49 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:49 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:49 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::volumioPause Dec 20 08:47:54 lucasplace volumio[998]: info: CoreStateMachine::pause Dec 20 08:47:54 lucasplace volumio[998]: info: CoreStateMachine::stPlaybackTimer Dec 20 08:47:54 lucasplace volumio[998]: info: CoreStateMachine::servicePause Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::servicePause Dec 20 08:47:54 lucasplace volumio[998]: info: Received pause Dec 20 08:47:54 lucasplace vtcs[1482]: [pause:147] Entering Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::servicePushState Dec 20 08:47:54 lucasplace volumio[998]: info: CoreStateMachine::pushState Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::volumioPushState Dec 20 08:47:54 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output update for this device Dec 20 08:47:54 lucasplace volumio[998]: info: MRS: Pushing multiroomSync output Dec 20 08:47:54 lucasplace volumio[998]: info: CoreCommandRouter::volumioGetState Dec 20 08:47:54 lucasplace volumio[998]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Dec 20 08:47:54 lucasplace vtcs[1482]: [feedThread:276] Exiting Dec 20 08:47:54 lucasplace vtcs[1482]: [pause:161] Exiting Dec 20 08:47:58 lucasplace volumio[998]: info: CoreCommandRouter::volumioRemoveQueueItem Dec 20 08:47:58 lucasplace volumio[998]: info: CoreStateMachine::removeQueueItem Dec 20 08:47:58 lucasplace volumio[998]: info: CoreStateMachine::stop Dec 20 08:47:58 lucasplace volumio[998]: info: CoreStateMachine::serviceStop Dec 20 08:47:58 lucasplace volumio[998]: info: CoreCommandRouter::serviceStop Dec 20 08:47:58 lucasplace vtcs[1482]: [close:85] Entering Dec 20 08:47:58 lucasplace vtcs[1482]: [close:100] Exiting Dec 20 08:47:58 lucasplace volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 08:47:58 lucasplace volumio[998]: TypeError: Cannot read property 'then' of undefined Dec 20 08:47:58 lucasplace volumio[998]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Dec 20 08:47:58 lucasplace volumio[998]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Dec 20 08:47:58 lucasplace volumio[998]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Dec 20 08:47:58 lucasplace volumio[998]: at Socket.emit (events.js:315:20) Dec 20 08:47:58 lucasplace volumio[998]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 20 08:47:58 lucasplace volumio[998]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 20 08:47:58 lucasplace volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 08:47:58 lucasplace vtcs[1482]: [2025-12-20 08:47:58] [info] asio async_shutdown error: asio.misc:2 (End of file) Dec 20 08:47:59 lucasplace vtcs[1482]: [2025-12-20 08:47:59.165] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:0 Dec 20 08:47:59 lucasplace sudo[12087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-20 08:46 Dec 20 08:47:59 lucasplace sudo[12087]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"