-- Logs begin at Sun 2025-05-04 01:26:42 IST, end at Sun 2025-05-04 01:32:12 IST. -- May 04 01:31:04 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri May 04 01:31:04 localhost volumio[1328]: info: [soundcloud] browseUri: soundcloud May 04 01:31:10 localhost volumio[1328]: error: [soundcloud] Failed to get top featured tracks in root view: 404 - Not Found FetchError: 404 - Not Found May 04 01:31:10 localhost volumio[1328]: at SoundCloud._SoundCloud_validateFetchResponse (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:402:11) May 04 01:31:10 localhost volumio[1328]: at SoundCloud._SoundCloud_fetchEndpoint (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:379:97) May 04 01:31:10 localhost volumio[1328]: at processTicksAndRejections (internal/process/task_queues.js:93:5) May 04 01:31:10 localhost volumio[1328]: at async SoundCloud._SoundCloud_fetchCollection (/data/plugins/music_service/soundcloud/node_modules/soundcloud-fetch/dist/cjs/lib/SoundCloud.js:367:18) May 04 01:31:10 localhost volumio[1328]: at async Cache.getOrSet (/data/plugins/music_service/soundcloud/dist/lib/util/Cache.js:77:23) May 04 01:31:10 localhost volumio[1328]: at async TrackModel._BaseModel_doLoopFetch (/data/plugins/music_service/soundcloud/dist/lib/model/BaseModel.js:112:25) May 04 01:31:10 localhost volumio[1328]: at async TrackViewHandler.browse (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/TrackViewHandler.js:48:22) May 04 01:31:10 localhost volumio[1328]: at async RootViewHandler._RootViewHandler_getTopFeaturedTracks (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/RootViewHandler.js:145:22) May 04 01:31:10 localhost volumio[1328]: at async Promise.all (index 1) May 04 01:31:10 localhost volumio[1328]: at async RootViewHandler.browse (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/view-handlers/RootViewHandler.js:29:30) May 04 01:31:10 localhost volumio[1328]: at async BrowseController.browseUri (/data/plugins/music_service/soundcloud/dist/lib/controller/browse/BrowseController.js:33:20) May 04 01:31:10 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:14 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:14 localhost volumio[1328]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 01:31:14 localhost volumio[1328]: info: CoreStateMachine::ClearQueue May 04 01:31:14 localhost volumio[1328]: info: CoreStateMachine::stop May 04 01:31:14 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:31:14 localhost volumio[1328]: info: CorePlayQueue::clearPlayQueue May 04 01:31:14 localhost volumio[1328]: info: CorePlayQueue::saveQueue May 04 01:31:14 localhost volumio[1328]: info: CoreCommandRouter::volumioPushQueue May 04 01:31:14 localhost volumio[1328]: info: CoreStateMachine::addQueueItems May 04 01:31:14 localhost volumio[1328]: info: CorePlayQueue::addQueueItems May 04 01:31:14 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:14 localhost volumio[1328]: info: Adding Item to queue: soundcloud/playlists@playlistId=1988906940 May 04 01:31:14 localhost volumio[1328]: info: Exploding uri soundcloud/playlists@playlistId=1988906940 in service soundcloud May 04 01:31:14 localhost volumio[1328]: info: [soundcloud] explodeUri: soundcloud/playlists@playlistId=1988906940 May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2073634980@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2073911452@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070718304@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070269156@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2068178300@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2064069220@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2062628220@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2064220052@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2063911756@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2067199696@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2071252532@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2069344864@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2077290336@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2069487228@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070910024@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2079343228@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2073720216@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2066844348@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2067257192@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2068949068@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2064277376@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2075107944@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2077907160@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070550688@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2077433496@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2024307260@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2074222236@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=1952700035@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2075562096@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2068117920@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070871996@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2070542848@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2082327336@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2074204356@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2065163948@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2074334632@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2067779084@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2066493704@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2079172580@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2073891576@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2074852416@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2072595464@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2071882932@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2068008028@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2063465100@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2077049800@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2066334440@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:15 localhost volumio[1328]: info: CoreCommandRouter::volumioPushQueue May 04 01:31:15 localhost volumio[1328]: info: CorePlayQueue::saveQueue May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::updateTrackBlock May 04 01:31:15 localhost volumio[1328]: info: CorePlayQueue::getTrackBlock May 04 01:31:15 localhost volumio[1328]: info: CoreCommandRouter::volumioPlay May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::play index 0 May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::stop May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::play index undefined May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:31:15 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:15 localhost volumio[1328]: info: CoreStateMachine::startPlaybackTimer May 04 01:31:15 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:15 localhost volumio[1328]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2073634980@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:16 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand stop May 04 01:31:16 localhost volumio[1328]: info: sendMpdCommand stop took 2 milliseconds May 04 01:31:16 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand clear May 04 01:31:16 localhost volumio[1328]: info: sendMpdCommand clear took 5 milliseconds May 04 01:31:16 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 5 milliseconds May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 4ms May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 3ms May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 3ms May 04 01:31:16 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:16 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:16 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 5ms May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 2ms May 04 01:31:16 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 01:31:16 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand play May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: May 04 01:31:16 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:16 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 3ms May 04 01:31:16 localhost volumio[1328]: info: sendMpdCommand play took 2 milliseconds May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 3ms May 04 01:31:16 localhost volumio[1328]: info: ------------------------------ 2ms May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 11 milliseconds May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: info: May 04 01:31:19 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 10 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 10 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 3 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus stop May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 18ms May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 5 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand status took 5 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 4 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 4 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 4 milliseconds May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:19 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:19 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:19 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 33ms May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 31ms May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 25ms May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 17 milliseconds May 04 01:31:19 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 18 milliseconds May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:19 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:19 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:19 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:19 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Out of Hand","artist":"(Preview) Molding Clay","album":"What Left Goes Right for U?","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:19 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:19 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:19 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 33ms May 04 01:31:19 localhost volumio[1328]: info: ------------------------------ 32ms May 04 01:31:32 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 04 01:31:32 localhost volumio[1328]: info: CURURI: music-library May 04 01:31:32 localhost mpd[1477]: exception: No database May 04 01:31:32 localhost volumio[1328]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database May 04 01:31:32 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:35 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:35 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:36 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:36 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:38 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:38 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:40 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:40 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:40 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:40 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:42 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:31:42 localhost volumio[1328]: info: Preload queue cleared May 04 01:31:48 localhost mpd[1477]: player: played "https://cf-preview-media.sndcdn.com/preview/0/30/YISUKs573BMd.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvWUlTVUtzNTczQk1kLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODgyfX19XX0_&Signature=RyCAXcYTvtSsYssQdc0zYQ2u3RxYAYcef06OY532AqfbtHa053JxkMRX4iSul2yUgorQOByALUwNvRWj-yiVn-DXb8FRSONgkuGyC86v-D~2wLf~syye7xLW3XJDjrBbs6~ZluubRaekM~80VFq7ImrEYP6HS5MlkO6G32P6avVmh3DgcBTAF4Fu1FtHydr~D7gs-YguaSNrgDTuDkvJfzJYyUUIFDSq19ajUDUzVGquxmJzdQu3lqcJNJP7Hif7-h7DXbEaYkj7K50mXaBGu5W6W1HvAL3iorO2Hpgd8J81RxWKkNF~g0Gwq6p-qqb9TYFX1-2ABG2zAhFC-DDnUA__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:48 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:48 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:48 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:48 localhost volumio[1328]: info: May 04 01:31:48 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:48 localhost volumio[1328]: error: updateQueue error: null May 04 01:31:48 localhost volumio[1328]: error: updateQueue error: null May 04 01:31:48 localhost volumio[1328]: error: updateQueue error: null May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 4ms May 04 01:31:48 localhost volumio[1328]: info: sendMpdCommand status took 3 milliseconds May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 4ms May 04 01:31:48 localhost volumio[1328]: info: sendMpdCommand status took 4 milliseconds May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 4ms May 04 01:31:48 localhost volumio[1328]: info: sendMpdCommand status took 3 milliseconds May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:48 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 0 May 04 01:31:48 localhost volumio[1328]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 04 01:31:48 localhost volumio[1328]: verbose: CURRENT POSITION 0 May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::syncState stateService stop May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::play index undefined May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd May 04 01:31:48 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::startPlaybackTimer May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2073911452@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1988906940%7D May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 16ms May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 15ms May 04 01:31:48 localhost volumio[1328]: info: ------------------------------ 14ms May 04 01:31:48 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:48 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:48 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:49 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand stop May 04 01:31:49 localhost volumio[1328]: info: sendMpdCommand stop took 0 milliseconds May 04 01:31:49 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand clear May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: sendMpdCommand clear took 0 milliseconds May 04 01:31:49 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: error: updateQueue error: null May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 2ms May 04 01:31:49 localhost volumio[1328]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 1 milliseconds May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:49 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:49 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:49 localhost volumio[1328]: verbose: MPD COMMAND [object Object] May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 2ms May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 2ms May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:49 localhost volumio[1328]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 01:31:49 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand play May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:49 localhost volumio[1328]: info: sendMpdCommand play took 1 milliseconds May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:49 localhost volumio[1328]: info: May 04 01:31:49 localhost volumio[1328]: ---------------------------- MPD announces system playlist update May 04 01:31:49 localhost volumio[1328]: info: Ignoring MPD Status Update May 04 01:31:49 localhost volumio[1328]: info: ------------------------------ 1ms May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: May 04 01:31:54 localhost volumio[1328]: ---------------------------- MPD announces state update: player May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::getState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand status May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand status took 8 milliseconds May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand status took 3 milliseconds May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseState May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus stop May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:54 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:54 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 22ms May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 31ms May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 31ms May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 21 milliseconds May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 21 milliseconds May 04 01:31:54 localhost volumio[1328]: info: sendMpdCommand playlistinfo took 22 milliseconds May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:54 localhost volumio[1328]: verbose: ControllerMpd::parseTrackInfo May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:54 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:54 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: ControllerMpd::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::servicePushState May 04 01:31:54 localhost volumio[1328]: info: CorePlayQueue::getTrack 1 May 04 01:31:54 localhost volumio[1328]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":205,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"YELLOW MAN","artist":"DROWNED","album":"YELLOW MAN","uri":"https://cf-media.sndcdn.com/ifYKo0exhxFf.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vaWZZS28wZXhoeEZmLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzQ2MzAyODM0fX19XX0_&Signature=TTbk4skhUtcJP8gw9L1~Vp~bH0Y9jBAgwd2rZwjTM11cT4beKpzdNo-FzdkwAw-4GmtUpDxblSwQai2m9hHsKhv~tpcv4VOG2NkQ5LDkVOEu2VjVrJS1XixShDFniP4aYg3QnIclGB8X98KZlyVYu8XHS9aOHO0QxN~4m9y1UHZQ7Nz9CeLI2Q8Cqbjkyqs2EoNalXVUtI2p-qqnOgDZSuokBkhMSBOCE9nNKZptRlRk-Jl4qx8ioDvooIUzw9GqRvXY-0RALtMCZ0d89SS38RePvj4g5E~riaEHr3hMqY5f21-hV2mM53unhiicE4c8PtRE5V8cNCkbs8NAhbDIjw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} May 04 01:31:54 localhost volumio[1328]: verbose: CURRENT POSITION 1 May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState stateService play May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::syncState currentStatus play May 04 01:31:54 localhost volumio[1328]: info: Received an update from plugin. extracting info from payload May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: CoreStateMachine::pushState May 04 01:31:54 localhost volumio[1328]: info: CoreCommandRouter::volumioPushState May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 57ms May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 56ms May 04 01:31:54 localhost volumio[1328]: info: ------------------------------ 57ms May 04 01:32:12 localhost volumio[1328]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 04 01:32:12 localhost volumio[1328]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 01:32:12 localhost volumio[1328]: TypeError: Cannot read property 'length' of undefined May 04 01:32:12 localhost volumio[1328]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 04 01:32:12 localhost volumio[1328]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 04 01:32:12 localhost volumio[1328]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 04 01:32:12 localhost volumio[1328]: at Parser.emit (events.js:315:20) May 04 01:32:12 localhost volumio[1328]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 04 01:32:12 localhost volumio[1328]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 04 01:32:12 localhost volumio[1328]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 04 01:32:12 localhost volumio[1328]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 04 01:32:12 localhost volumio[1328]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 04 01:32:12 localhost volumio[1328]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 04 01:32:12 localhost volumio[1328]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 04 01:32:12 localhost volumio[1328]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 04 01:32:12 localhost volumio[1328]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 04 01:32:12 localhost volumio[1328]: at IncomingMessage.emit (events.js:327:22) May 04 01:32:12 localhost volumio[1328]: at endReadableNT (internal/streams/readable.js:1327:12) May 04 01:32:12 localhost volumio[1328]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 04 01:32:12 localhost volumio[1328]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 01:32:12 localhost sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 01:31 May 04 01:32:12 localhost sudo[2551]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"