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"