Dec 21 03:28:04 volumio volumio[1383]: info: [jellyfin-poller] Polled http://172.16.0.12: offline Dec 21 03:28:14 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 03:28:34 volumio volumio[1383]: info: [jellyfin-poller] Polled http://172.16.0.12: offline Dec 21 03:28:35 volumio volumio[1383]: info: CoreCommandRouter::volumioGetState Dec 21 03:28:35 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:39 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:28:39 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin Dec 21 03:28:39 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:28:41 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:28:41 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c Dec 21 03:28:41 volumio volumio[1383]: info: [jellyfin-conn] Generated new device Id for f13@jellyfin: 8da6c32d-dd09-416d-ba03-fd54a7b66661 Dec 21 03:28:41 volumio wpa_supplicant[1040]: TDLS: Invalid frame - payloadtype=1 category=240 action=26 Dec 21 03:28:41 volumio volumio[1383]: info: [jellyfin-conn] Login successful: f13@jellyfin Dec 21 03:28:41 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Dec 21 03:28:41 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/UserViews?userId=c893c7b51bd54bfaacb288e89539c216 Dec 21 03:28:41 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"28e9960207c978c0d9aaefc8ae2d3a79","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Dec 21 03:28:41 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=28e9960207c978c0d9aaefc8ae2d3a79&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:28:41 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:28:41 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:28:48 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::stop Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 21 03:28:48 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:28:48 volumio volumio[1383]: info: Adding Item to queue: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=e70807fceb754204b9a7ea97a3ae87c4 Dec 21 03:28:48 volumio volumio[1383]: info: Exploding uri jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=e70807fceb754204b9a7ea97a3ae87c4 in service jellyfin Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-browse] explodeUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=e70807fceb754204b9a7ea97a3ae87c4 Dec 21 03:28:48 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"e70807fceb754204b9a7ea97a3ae87c4","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand stop took 11 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand status took 1 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Song for ××(A BEST -15th Anniversary Edition-)","artist":"浜崎あゆみ","album":"A BEST -15th Anniversary Edition-","uri":"NAS/nas/滨崎步 A BEST -15th Anniversary Edition/001-A Song for ××(A BEST -15th Anniversary Edition-).flac","trackType":"flac"} Dec 21 03:28:48 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState stateService stop Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: No code Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 25ms Dec 21 03:28:48 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&recursive=true&sortOrder=Ascending&parentId=e70807fceb754204b9a7ea97a3ae87c4&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/song@songId=6c245bab9f6a4a98bd90e4e1a44dbb2c Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-play] Stream URL for Prolongue: http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5 Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand stop took 1 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:28:48 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand clear took 0 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac" Dec 21 03:28:48 volumio volumio[1383]: error: updateQueue error: null Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 38ms Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand addid "http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac" Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:28:48 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand addid "http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac" took 0 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:28:48 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:28:48 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:28:48 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 4ms Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 2ms Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand play took 1 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:28:48 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:28:48 volumio volumio[1383]: info: Dec 21 03:28:48 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand status took 46 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand status took 39 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand status took 36 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 40ms Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand status took 39 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 21 03:28:48 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:28:48 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":479,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"705 Kbps","isStreaming":false,"title":"Prolongue","artist":"Ayumi hamasaki","album":"A Song for XX","uri":"http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac","trackType":"flac"} Dec 21 03:28:48 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:28:48 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:28:48 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:28:48 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":85,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"735 Kbps","isStreaming":false,"title":"Prolongue","artist":"Ayumi hamasaki","album":"A Song for XX","uri":"http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac","trackType":"flac"} Dec 21 03:28:48 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus play Dec 21 03:28:48 volumio volumio[1383]: info: Received an update from plugin. extracting info from payload Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:28:48 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 84ms Dec 21 03:28:48 volumio volumio[1383]: info: ------------------------------ 82ms Dec 21 03:28:48 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:28:48 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-play]: Mark song Prolongue as played by f13. Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-play]: Reported 'start' for song: Prolongue (at 479 ms) Dec 21 03:28:48 volumio volumio[1383]: info: [jellyfin-play]: Reported 'timeupdate' for song: Prolongue (at 958 ms) Dec 21 03:28:52 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:28:52 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/albums@parentId=28e9960207c978c0d9aaefc8ae2d3a79@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@noExplode=1 Dec 21 03:28:52 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"28e9960207c978c0d9aaefc8ae2d3a79","startIndex":0,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Dec 21 03:28:52 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=28e9960207c978c0d9aaefc8ae2d3a79&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:28:52 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:02 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:29:02 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/albums@parentId=28e9960207c978c0d9aaefc8ae2d3a79@sortBy=DateCreated%2CSortName@sortOrder=Descending%2CAscending@fixedView=latest@startIndex=47@noExplode=1 Dec 21 03:29:02 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"28e9960207c978c0d9aaefc8ae2d3a79","startIndex":47,"limit":47,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Dec 21 03:29:02 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=47&limit=47&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=28e9960207c978c0d9aaefc8ae2d3a79&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:29:02 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:04 volumio volumio[1383]: info: [jellyfin-poller] Polled http://172.16.0.12: offline Dec 21 03:29:17 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:29:17 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c Dec 21 03:29:17 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Dec 21 03:29:17 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/UserViews?userId=c893c7b51bd54bfaacb288e89539c216 Dec 21 03:29:17 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"28e9960207c978c0d9aaefc8ae2d3a79","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Dec 21 03:29:17 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=28e9960207c978c0d9aaefc8ae2d3a79&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:29:18 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:18 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:23 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::stop Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 21 03:29:23 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:23 volumio volumio[1383]: info: Adding Item to queue: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=7f2ef6b7646ffe7009c65e4c27ce80fa Dec 21 03:29:23 volumio volumio[1383]: info: Exploding uri jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=7f2ef6b7646ffe7009c65e4c27ce80fa in service jellyfin Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-browse] explodeUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=7f2ef6b7646ffe7009c65e4c27ce80fa Dec 21 03:29:23 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7f2ef6b7646ffe7009c65e4c27ce80fa","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand stop took 19 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 1 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 1 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prolongue","artist":"Ayumi hamasaki","album":"A Song for XX","uri":"http://172.16.0.12:8096/Audio/6c245bab9f6a4a98bd90e4e1a44dbb2c/stream.flac?static=true&mediaSourceId=6c245bab9f6a4a98bd90e4e1a44dbb2c&tag=0ecaa3923f1a58822a9603ef7fe9a6c5&t.flac","trackType":"flac"} Dec 21 03:29:23 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState stateService stop Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: No code Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 15ms Dec 21 03:29:23 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:23 volumio volumio[1383]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/song@songId=6c245bab9f6a4a98bd90e4e1a44dbb2c","favourite":false} Dec 21 03:29:23 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&recursive=true&sortOrder=Ascending&parentId=7f2ef6b7646ffe7009c65e4c27ce80fa&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/song@songId=49ef6f44958e74fe9440bc4ff2d95816 Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play]: Reported 'stop' for song: Prolongue (at 35245 ms) Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play] Stream URL for Not yet: http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469 Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand stop took 1 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand clear took 2 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac" Dec 21 03:29:23 volumio volumio[1383]: error: updateQueue error: null Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 28ms Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand addid "http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac" Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand addid "http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac" took 5 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:23 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:23 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 2ms Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 5ms Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand play took 5 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play]: Mark song Not yet as played by f13. Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 17 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 17 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: info: Dec 21 03:29:23 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:23 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 6 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand status took 2 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"461 Kbps","isStreaming":false,"title":"Not yet","artist":"Ayumi hamasaki","album":"Secret","uri":"http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac","trackType":"flac"} Dec 21 03:29:23 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 25ms Dec 21 03:29:23 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 8ms Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 21 03:29:23 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:23 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:23 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:23 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":122,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"512 Kbps","isStreaming":false,"title":"Not yet","artist":"Ayumi hamasaki","album":"Secret","uri":"http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac","trackType":"flac"} Dec 21 03:29:23 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus play Dec 21 03:29:23 volumio volumio[1383]: info: Received an update from plugin. extracting info from payload Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:23 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:23 volumio volumio[1383]: info: ------------------------------ 18ms Dec 21 03:29:23 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play]: Reported 'timeupdate' for song: Not yet (at 958 ms) Dec 21 03:29:23 volumio volumio[1383]: info: [jellyfin-play]: Reported 'start' for song: Not yet (at 0 ms) Dec 21 03:29:27 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:29:27 volumio volumio[1383]: info: ControllerMpd::stop Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 21 03:29:27 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:27 volumio volumio[1383]: info: Adding Item to queue: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=a74dbc9f2d76bc838cd41fea073b7189 Dec 21 03:29:27 volumio volumio[1383]: info: Exploding uri jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=a74dbc9f2d76bc838cd41fea073b7189 in service jellyfin Dec 21 03:29:27 volumio volumio[1383]: info: [jellyfin-browse] explodeUri: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/songs@albumId=a74dbc9f2d76bc838cd41fea073b7189 Dec 21 03:29:27 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"c893c7b51bd54bfaacb288e89539c216","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"a74dbc9f2d76bc838cd41fea073b7189","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Dec 21 03:29:27 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:27 volumio volumio[1383]: info: Dec 21 03:29:27 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:27 volumio volumio[1383]: info: sendMpdCommand stop took 16 milliseconds Dec 21 03:29:27 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:27 volumio volumio[1383]: info: sendMpdCommand status took 3 milliseconds Dec 21 03:29:27 volumio volumio[1383]: info: sendMpdCommand status took 2 milliseconds Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:27 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 21 03:29:27 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:27 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:27 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:27 volumio volumio[1383]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Not yet","artist":"Ayumi hamasaki","album":"Secret","uri":"http://172.16.0.12:8096/Audio/49ef6f44958e74fe9440bc4ff2d95816/stream.flac?static=true&mediaSourceId=49ef6f44958e74fe9440bc4ff2d95816&tag=2996060fb06c74a1bda047aa1add9469&t.flac","trackType":"flac"} Dec 21 03:29:27 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::syncState stateService stop Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:27 volumio volumio[1383]: info: No code Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:27 volumio volumio[1383]: info: ------------------------------ 12ms Dec 21 03:29:27 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:27 volumio volumio[1383]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/song@songId=49ef6f44958e74fe9440bc4ff2d95816","favourite":false} Dec 21 03:29:27 volumio volumio[1383]: info: [jellyfin-play]: Reported 'stop' for song: Not yet (at 5570 ms) Dec 21 03:29:27 volumio volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://172.16.0.12:8096/Items?userId=c893c7b51bd54bfaacb288e89539c216&startIndex=0&recursive=true&sortOrder=Ascending&parentId=a74dbc9f2d76bc838cd41fea073b7189&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:27 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:27 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 21 03:29:27 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:27 volumio volumio[1383]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/f13@b3d34f4a85f84b2e825c36e120c3558c/song@songId=0f5cefe0d94aa89b085771853be88e1d Dec 21 03:29:28 volumio volumio[1383]: info: [jellyfin-play] Stream URL for Catcher In The Light: http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32 Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand stop took 0 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:28 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand clear took 0 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac" Dec 21 03:29:28 volumio volumio[1383]: error: updateQueue error: null Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 1ms Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand addid "http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac" Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:28 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand addid "http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac" took 0 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:28 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:28 volumio volumio[1383]: verbose: MPD COMMAND [object Object] Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:28 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 1ms Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 2ms Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand play took 3 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: [jellyfin-play]: Mark song Catcher In The Light as played by f13. Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 9 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 8 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:28 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: Dec 21 03:29:28 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 9 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 3 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:28 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:28 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":164,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Catcher In The Light","artist":"Ayumi hamasaki","album":"My Story","uri":"http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac","trackType":"flac"} Dec 21 03:29:28 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 11ms Dec 21 03:29:28 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 14 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 15ms Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand status took 14 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:28 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:28 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":164,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Catcher In The Light","artist":"Ayumi hamasaki","album":"My Story","uri":"http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac","trackType":"flac"} Dec 21 03:29:28 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus play Dec 21 03:29:28 volumio volumio[1383]: info: Received an update from plugin. extracting info from payload Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 41ms Dec 21 03:29:28 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 21 03:29:28 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:28 volumio volumio[1383]: info: ControllerMpd::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Dec 21 03:29:28 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:28 volumio volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":164,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"525 Kbps","isStreaming":false,"title":"Catcher In The Light","artist":"Ayumi hamasaki","album":"My Story","uri":"http://172.16.0.12:8096/Audio/0f5cefe0d94aa89b085771853be88e1d/stream.flac?static=true&mediaSourceId=0f5cefe0d94aa89b085771853be88e1d&tag=1e4e9218ebdec79cb32b63bcfc2f3c32&t.flac","trackType":"flac"} Dec 21 03:29:28 volumio volumio[1383]: verbose: CURRENT POSITION 0 Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState stateService play Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus play Dec 21 03:29:28 volumio volumio[1383]: info: Received an update from plugin. extracting info from payload Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:28 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:28 volumio volumio[1383]: info: ------------------------------ 45ms Dec 21 03:29:28 volumio volumio[1383]: info: Pushing Favourites {"favourite":false} Dec 21 03:29:28 volumio volumio[1383]: info: [jellyfin-play]: Reported 'start' for song: Catcher In The Light (at 0 ms) Dec 21 03:29:28 volumio volumio[1383]: info: [jellyfin-play]: Reported 'timeupdate' for song: Catcher In The Light (at 0 ms) Dec 21 03:29:28 volumio volumio[1383]: info: [jellyfin-play]: Reported 'timeupdate' for song: Catcher In The Light (at 958 ms) Dec 21 03:29:34 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 03:29:34 volumio volumio[1383]: info: [jellyfin-browse] browseUri: jellyfin Dec 21 03:29:34 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:34 volumio volumio[1383]: info: [jellyfin-poller] Polled http://172.16.0.12: offline Dec 21 03:29:36 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 21 03:29:36 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 03:29:36 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:39 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 21 03:29:39 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:42 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 03:29:42 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Dec 21 03:29:42 volumio systemd[1]: welcome.service: Deactivated successfully. Dec 21 03:29:42 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 21 03:29:42 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 21 03:29:42 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 21 03:29:42 volumio welcome[21502]: Resolved ip:[1] 172.16.0.42 Dec 21 03:29:42 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 21 03:29:42 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 21 03:29:42 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 21 03:29:43 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:52 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::ClearQueue Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::pushState Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::serviceStop Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::serviceStop Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 03:29:52 volumio volumio[1383]: info: ControllerMpd::stop Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::clearPlayQueue Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::addQueueItems Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::addQueueItems Dec 21 03:29:52 volumio volumio[1383]: info: Preload queue cleared Dec 21 03:29:52 volumio volumio[1383]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978 Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::volumioPushQueue Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::saveQueue Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::updateTrackBlock Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrackBlock Dec 21 03:29:52 volumio volumio[1383]: info: CoreCommandRouter::volumioPlay Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::play index 0 Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::stop Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::play index undefined Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:52 volumio volumio[1383]: info: CoreStateMachine::startPlaybackTimer Dec 21 03:29:52 volumio volumio[1383]: info: CorePlayQueue::getTrack 0 Dec 21 03:29:52 volumio volumio[1383]: info: [1766258992930] ControllerWebradio::clearAddPlayTrack Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 03:29:52 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:52 volumio volumio[1383]: info: Dec 21 03:29:52 volumio volumio[1383]: ---------------------------- MPD announces state update: player Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand stop took 11 milliseconds Dec 21 03:29:52 volumio volumio[1383]: info: ControllerMpd::getState Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand stop took 5 milliseconds Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand status took 1 milliseconds Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand status took 1 milliseconds Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand clear took 1 milliseconds Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::parseState Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" Dec 21 03:29:52 volumio volumio[1383]: info: Dec 21 03:29:52 volumio volumio[1383]: ---------------------------- MPD announces system playlist update Dec 21 03:29:52 volumio volumio[1383]: info: Ignoring MPD Status Update Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 03:29:52 volumio volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:52 volumio volumio[1383]: verbose: ControllerMpd::parseTrackInfo Dec 21 03:29:52 volumio volumio[1383]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 21 03:29:52 volumio volumio[1383]: info: ------------------------------ 4ms Dec 21 03:29:52 volumio volumio[1383]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 03:29:52 volumio volumio[1383]: TypeError: Cannot read properties of undefined (reading 'split') Dec 21 03:29:52 volumio volumio[1383]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Dec 21 03:29:52 volumio volumio[1383]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 03:29:52 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 21 03:29:52 volumio volumio[1383]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 03:29:53 volumio sudo[21558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 03:28' Dec 21 03:29:53 volumio sudo[21558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"