-- Logs begin at Fri 2024-04-12 00:18:32 CEST, end at Sat 2024-04-20 12:43:50 CEST. --
Apr 20 12:42:00 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/3ad8874d0fc3cf829c6aaa5258ad1b27
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/c63fcfc80adeb358928185a1be1624cb
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/1b92c2cc9577ab96f9b12b2228d8df80
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/ed695c9979e5c10fc3c0862724ab4781
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/3abf01dd21e317a730eb6a2859f0bc59
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/7399dc126ca201f32a38214fc0348bd0
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/ed5e1ae859374d6b017ffc52c870cb21
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/2b8f8b71de9a816de7d24fe3c1cd5971
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/8000062dd6c0c1bf269a34d5e69a1f5b
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/202e2d0c8fa3917a633d6f72ded62554
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/76313e98f33c4b29c82b1f7f4cbc7bae
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/c9beb2bd233f605eb032c869c4df5bd2
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/491a2ebbc646533b4b3078591683a695
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/a4dd69e31d432f912de4c76bdd0285b4
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/7bd93bf3d595226305ea63b74056c63e
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/e388313c9c12f1b2247e28b867f062e7
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/79f9413fe0a3ab2651b4ef9ffbd1ecab
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/8b1f3fb72db34d47001b42034db05d4f
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/072b21845c9e22afc1bd1e47658c597c
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/aa513392ba899f2298dc481b9ee9b02c
Apr 20 12:42:00 volumio volumio[821]: info: Preloading song: volusonic/track/0a24f020f31eac907c904790bb5596f3
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/3ad8874d0fc3cf829c6aaa5258ad1b27 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/c63fcfc80adeb358928185a1be1624cb in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/1b92c2cc9577ab96f9b12b2228d8df80 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/ed695c9979e5c10fc3c0862724ab4781 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/3abf01dd21e317a730eb6a2859f0bc59 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: No valid Plugin REST Endpoint
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/7399dc126ca201f32a38214fc0348bd0 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/ed5e1ae859374d6b017ffc52c870cb21 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/2b8f8b71de9a816de7d24fe3c1cd5971 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/8000062dd6c0c1bf269a34d5e69a1f5b in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/202e2d0c8fa3917a633d6f72ded62554 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/76313e98f33c4b29c82b1f7f4cbc7bae in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/c9beb2bd233f605eb032c869c4df5bd2 in service volusonic
Apr 20 12:42:00 volumio kernel: hwmon hwmon1: Undervoltage detected!
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/491a2ebbc646533b4b3078591683a695 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/a4dd69e31d432f912de4c76bdd0285b4 in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/7bd93bf3d595226305ea63b74056c63e in service volusonic
Apr 20 12:42:00 volumio volumio[821]: info: Exploding uri volusonic/track/e388313c9c12f1b2247e28b867f062e7 in service volusonic
Apr 20 12:42:01 volumio volumio[821]: info: Exploding uri volusonic/track/79f9413fe0a3ab2651b4ef9ffbd1ecab in service volusonic
Apr 20 12:42:01 volumio volumio[821]: info: Exploding uri volusonic/track/8b1f3fb72db34d47001b42034db05d4f in service volusonic
Apr 20 12:42:01 volumio volumio[821]: info: Exploding uri volusonic/track/072b21845c9e22afc1bd1e47658c597c in service volusonic
Apr 20 12:42:01 volumio volumio[821]: info: Exploding uri volusonic/track/aa513392ba899f2298dc481b9ee9b02c in service volusonic
Apr 20 12:42:01 volumio volumio[821]: info: Exploding uri volusonic/track/0a24f020f31eac907c904790bb5596f3 in service volusonic
Apr 20 12:42:06 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::ClearQueue
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::addQueueItems
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::addQueueItems
Apr 20 12:42:06 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:42:06 volumio volumio[821]: info: Adding Item to queue: volusonic/random/ece3399c7582f06e7a96e609867ae8d9
Apr 20 12:42:06 volumio volumio[821]: info: Exploding uri volusonic/random/ece3399c7582f06e7a96e609867ae8d9 in service volusonic
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrackBlock
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioPlay
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::play index 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::play index undefined
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: [1713609726525] ControllerVolusonic::clearAddPlayTrack
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand stop took 10 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:42:06 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand clear took 8 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:42:06 volumio volumio[821]: error: updateQueue error: null
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 19ms
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:42:06 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic" took 14 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 11ms
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand play took 8 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand status took 20 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:06 volumio volumio[821]: info: [1713609726669] ControllerVolusonic::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:42:06 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop
Apr 20 12:42:06 volumio volumio[821]: info: [1713609726727] ControllerVolusonic: MPD player state update
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:42:06 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:42:06 volumio volumio[821]: info:
Apr 20 12:42:06 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand status took 31 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand status took 30 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand status took 34 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 37ms
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand status took 34 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand playlistinfo took 21 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand playlistinfo took 20 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: [1713609726816] ControllerVolusonic::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":54,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Person Of Interest","artist":"Ramin Djawadi","album":"Person Of Interest (Original Television Soundtrack)","uri":"http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:42:06 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:42:06 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 170ms
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand playlistinfo took 130 milliseconds
Apr 20 12:42:06 volumio volumio[821]: info: sendMpdCommand playlistinfo took 125 milliseconds
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:06 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:06 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:06 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 213ms
Apr 20 12:42:06 volumio volumio[821]: info: ------------------------------ 212ms
Apr 20 12:42:06 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue
Apr 20 12:42:06 volumio volumio[821]: info: CoreStateMachine::getQueue
Apr 20 12:42:06 volumio volumio[821]: info: CorePlayQueue::getQueue
Apr 20 12:42:07 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37
Apr 20 12:42:07 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37
Apr 20 12:42:24 volumio kernel: usb 1-1.2: USB disconnect, device number 6
Apr 20 12:42:24 volumio volumio[821]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-USB_External_Disk_3.0_987654321010-0:0-part1 /dev/disk/by-label/Récupération /dev/disk/by-partuuid/bd5dcc8d-93ff-4b58-996a-f56b885a7b09 /dev/disk/by-uuid/4216125C16125171 /dev/disk/by-partlabel/Basic\\x20data\\x20partition","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_FS_LABEL":"Récupération","ID_FS_LABEL_ENC":"Récupération","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4216125C16125171","ID_FS_UUID_ENC":"4216125C16125171","ID_INSTANCE":"0:0","ID_MODEL":"External_Disk_3.0","ID_MODEL_ENC":"External\\x20Disk\\x203.0","ID_MODEL_ID":"1576","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x8000000000000001","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1021952","ID_PART_ENTRY_TYPE":"de94bba4-06d1-4d40-a16a-bfd50179d6ac","ID_PART_ENTRY_UUID":"bd5dcc8d-93ff-4b58-996a-f56b885a7b09","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"535ee980-b106-4507-ada0-5c8116a0a10a","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"USB_External_Disk_3.0_987654321010-0:0","ID_SERIAL_SHORT":"987654321010","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"USB\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Basic data partition","SEQNUM":"288399","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4147544602362"}
Apr 20 12:42:24 volumio sudo[13271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1
Apr 20 12:42:24 volumio sudo[13271]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:42:24 volumio kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Apr 20 12:42:24 volumio kernel: sd 1:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK
Apr 20 12:42:24 volumio ntfs-3g[9608]: Unmounting /dev/sdb1 (Récupération)
Apr 20 12:42:24 volumio ntfs-3g[9608]: Failed to sync device /dev/sdb1: Input/output error
Apr 20 12:42:24 volumio ntfs-3g[9608]: Failed to close volume /dev/sdb1: Input/output error
Apr 20 12:42:24 volumio systemd[1]: media-R\xc3\xa9cup\xc3\xa9ration.mount: Succeeded.
Apr 20 12:42:24 volumio sudo[13271]: pam_unix(sudo:session): session closed for user root
Apr 20 12:42:24 volumio volumio[821]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-USB_External_Disk_3.0_987654321010-0:0-part2 /dev/disk/by-uuid/D813-86F6 /dev/disk/by-partuuid/acd8ba7b-1eef-4759-bdac-e1370a66ca58 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-partlabel/EFI\\x20system\\x20partition","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"D813-86F6","ID_FS_UUID_ENC":"D813-86F6","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"External_Disk_3.0","ID_MODEL_ENC":"External\\x20Disk\\x203.0","ID_MODEL_ID":"1576","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x8000000000000000","ID_PART_ENTRY_NAME":"EFI\\x20system\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"1024000","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"204800","ID_PART_ENTRY_TYPE":"c12a7328-f81f-11d2-ba4b-00a0c93ec93b","ID_PART_ENTRY_UUID":"acd8ba7b-1eef-4759-bdac-e1370a66ca58","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"535ee980-b106-4507-ada0-5c8116a0a10a","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"USB_External_Disk_3.0_987654321010-0:0","ID_SERIAL_SHORT":"987654321010","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"USB\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"EFI system partition","SEQNUM":"288400","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4147544559539"}
Apr 20 12:42:24 volumio sudo[13274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb2
Apr 20 12:42:24 volumio sudo[13274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 20 12:42:24 volumio systemd[1]: media-D813\x2d86F6.mount: Succeeded.
Apr 20 12:42:24 volumio sudo[13274]: pam_unix(sudo:session): session closed for user root
Apr 20 12:42:24 volumio volumio[821]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb4","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/Nouveau\\x20nom /dev/disk/by-id/usb-USB_External_Disk_3.0_987654321010-0:0-part4 /dev/disk/by-uuid/E4E6-ED72 /dev/disk/by-partuuid/6e377aec-f6cf-4405-a536-de42b471870f /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part4 /dev/disk/by-partlabel/Basic\\x20data\\x20partition","DEVNAME":"/dev/sdb4","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb4","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_FS_LABEL":"Nouveau_nom","ID_FS_LABEL_ENC":"Nouveau\\x20nom","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"E4E6-ED72","ID_FS_UUID_ENC":"E4E6-ED72","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"External_Disk_3.0","ID_MODEL_ENC":"External\\x20Disk\\x203.0","ID_MODEL_ID":"1576","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"4","ID_PART_ENTRY_OFFSET":"1261568","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"248805376","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"6e377aec-f6cf-4405-a536-de42b471870f","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"535ee980-b106-4507-ada0-5c8116a0a10a","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"USB_External_Disk_3.0_987654321010-0:0","ID_SERIAL_SHORT":"987654321010","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"USB\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"20","PARTN":"4","PARTNAME":"Basic data partition","SEQNUM":"288402","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4147544519787"}
Apr 20 12:42:24 volumio volumio[821]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/62548a13-1e98-4831-9daa-0c59e8c77095 /dev/disk/by-id/usb-USB_External_Disk_3.0_987654321010-0:0-part3 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part3","DEVNAME":"/dev/sdb3","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb3","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"External_Disk_3.0","ID_MODEL_ENC":"External\\x20Disk\\x203.0","ID_MODEL_ID":"1576","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x8000000000000000","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"1228800","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"32768","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"62548a13-1e98-4831-9daa-0c59e8c77095","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"535ee980-b106-4507-ada0-5c8116a0a10a","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"0204","ID_SERIAL":"USB_External_Disk_3.0_987654321010-0:0","ID_SERIAL_SHORT":"987654321010","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"USB\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"19","PARTN":"3","PARTNAME":"Microsoft reserved partition","SEQNUM":"288401","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4147544519032"}
Apr 20 12:42:24 volumio volumio[821]: error: Cannot associate FS Label, not mounting
Apr 20 12:42:28 volumio volumio[821]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Récupération
Apr 20 12:42:28 volumio volumio[821]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/D813-86F6
Apr 20 12:42:29 volumio volumio[821]: info: Scanning removed location : "USB/Récupération"
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:29 volumio volumio[821]: info:
Apr 20 12:42:29 volumio volumio[821]: ---------------------------- MPD announces state update: update
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:29 volumio volumio[821]: info: MPD Database updated - AlbumList cache refreshed
Apr 20 12:42:29 volumio volumio[821]: info:
Apr 20 12:42:29 volumio volumio[821]: ---------------------------- MPD announces state update: database
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:29 volumio volumio[821]: info:
Apr 20 12:42:29 volumio volumio[821]: ---------------------------- MPD announces state update: update
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand status took 12 milliseconds
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand status took 11 milliseconds
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:29 volumio volumio[821]: info: Command Router : Notfying DB Updatefalse
Apr 20 12:42:29 volumio volumio[821]: info: CoreCommandRouter::Close All Modals sent
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand status took 94 milliseconds
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand status took 93 milliseconds
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand status took 93 milliseconds
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand playlistinfo took 20 milliseconds
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:29 volumio volumio[821]: info: Command Router : Notfying DB Updatefalse
Apr 20 12:42:29 volumio volumio[821]: info: CoreCommandRouter::Close All Modals sent
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:29 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:29 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:29 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:29 volumio volumio[821]: info: ------------------------------ 175ms
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 20 12:42:29 volumio volumio[821]: info: sendMpdCommand playlistinfo took 18 milliseconds
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:29 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:29 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:29 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:29 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:29 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:29 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:29 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:29 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:29 volumio volumio[821]: info: ------------------------------ 191ms
Apr 20 12:42:29 volumio volumio[821]: info: ------------------------------ 191ms
Apr 20 12:42:36 volumio kernel: hwmon hwmon1: Voltage normalised
Apr 20 12:42:40 volumio kernel: hwmon hwmon1: Undervoltage detected!
Apr 20 12:42:46 volumio volumio[821]: info: VolumeController::SetAlsaVolume52
Apr 20 12:42:46 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:42:46 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:46 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:42:46 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:42:46 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:42:46 volumio volumio[821]: SPOTIFY: SPOTIFY VOLUME 37
Apr 20 12:42:46 volumio volumio[821]: SPOTIFY: VOLUMIO VOLUME 52
Apr 20 12:42:46 volumio volumio[821]: SPOTIFY: DELTA VOLUME ENOUGH: true
Apr 20 12:42:46 volumio volumio[821]: info: Setting Spotify Volume from Volumio: 52
Apr 20 12:42:48 volumio volumio[821]: SPOTIFY: SETTING SPOTIFY VOLUME 52
Apr 20 12:42:48 volumio volumio[821]: info: Sending Spotify command with payload to local API: /player/volume
Apr 20 12:42:55 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:55 volumio volumio[821]: info: CorePlayQueue::getTrack 1
Apr 20 12:42:55 volumio volumio[821]: info: Prefetching next song
Apr 20 12:42:55 volumio volumio[821]: info: [1713609775921] ControllerVolusonic::prefetch
Apr 20 12:42:55 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=c63fcfc80adeb358928185a1be1624cb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:42:55 volumio volumio[821]: info: sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=c63fcfc80adeb358928185a1be1624cb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic" took 1 milliseconds
Apr 20 12:42:55 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand consume 1
Apr 20 12:42:55 volumio volumio[821]: info:
Apr 20 12:42:55 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:42:55 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:42:55 volumio volumio[821]: info: sendMpdCommand consume 1 took 2 milliseconds
Apr 20 12:42:55 volumio volumio[821]: info: ------------------------------ 3ms
Apr 20 12:42:59 volumio volumio[821]: info:
Apr 20 12:42:59 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:42:59 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:42:59 volumio volumio[821]: info: [1713609779782] ControllerVolusonic: MPD player state update
Apr 20 12:42:59 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:59 volumio volumio[821]: info:
Apr 20 12:42:59 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:42:59 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:42:59 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds
Apr 20 12:42:59 volumio volumio[821]: info: ------------------------------ 6ms
Apr 20 12:42:59 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:42:59 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 20 12:42:59 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:59 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:42:59 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:59 volumio volumio[821]: info: [1713609779791] ControllerVolusonic::pushState
Apr 20 12:42:59 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:59 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:59 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53977,"duration":54,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Person Of Interest","artist":"Ramin Djawadi","album":"Person Of Interest (Original T","uri":"http://192.168.1.250:4533/rest/stream.view?id=3ad8874d0fc3cf829c6aaa5258ad1b27&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:42:59 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:42:59 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:42:59 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:42:59 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:42:59 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:42:59 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:59 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:42:59 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:42:59 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:42:59 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:59 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:42:59 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:42:59 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:42:59 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:42:59 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:42:59 volumio volumio[821]: info: ------------------------------ 22ms
Apr 20 12:42:59 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:42:59 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:00 volumio volumio[821]: info:
Apr 20 12:43:00 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:00 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:00 volumio volumio[821]: info: [1713609780031] ControllerVolusonic: MPD player state update
Apr 20 12:43:00 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:00 volumio volumio[821]: info:
Apr 20 12:43:00 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:00 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:00 volumio volumio[821]: info: sendMpdCommand status took 13 milliseconds
Apr 20 12:43:00 volumio volumio[821]: info: ------------------------------ 15ms
Apr 20 12:43:00 volumio volumio[821]: info: sendMpdCommand status took 12 milliseconds
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:00 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:00 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:00 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:00 volumio volumio[821]: info: [1713609780053] ControllerVolusonic::pushState
Apr 20 12:43:00 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:00 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":192,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Watching With Ten Thousand Eyes","artist":"Ramin Djawadi","album":"Person Of Interest (Original Television Soundtrack)","uri":"http://192.168.1.250:4533/rest/stream.view?id=c63fcfc80adeb358928185a1be1624cb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:00 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:00 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:00 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:43:00 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:43:00 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:00 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:00 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:00 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:00 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:00 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:00 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:00 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:00 volumio volumio[821]: info: ------------------------------ 38ms
Apr 20 12:43:00 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:00 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:00 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer
Apr 20 12:43:00 volumio volumio[821]: info: CorePlayQueue::getTrack 1
Apr 20 12:43:01 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:01 volumio volumio[821]: info: CorePlayQueue::getTrack 1
Apr 20 12:43:01 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:01 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:01 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue
Apr 20 12:43:01 volumio volumio[821]: info: CoreStateMachine::getQueue
Apr 20 12:43:01 volumio volumio[821]: info: CorePlayQueue::getQueue
Apr 20 12:43:01 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri
Apr 20 12:43:10 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/1ef69b9bf5d50162e6947ad9b827b4eb
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/c831038311035f78dc205514ffaaeb46
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/16c8d08879983e642adccb24c308abf8
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/7950c9b6f1d5578ba0d8174114e8a08c
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/f132c6a30455a7c0e6f26a92a2f9a0d3
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/e4c1706d3c2c3c6032bade10c4db8a95
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/8fef69ba55182e2a254606cfaddd244f
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/61bd6b315384556ef9a85c6e3bae5bb1
Apr 20 12:43:10 volumio volumio[821]: info: Preloading song: volusonic/track/a328bdb8e90a29929ce59994dea3606c
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/1ef69b9bf5d50162e6947ad9b827b4eb in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/c831038311035f78dc205514ffaaeb46 in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/16c8d08879983e642adccb24c308abf8 in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: No valid Plugin REST Endpoint
Apr 20 12:43:10 volumio volumio[821]: info: No valid Plugin REST Endpoint
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/7950c9b6f1d5578ba0d8174114e8a08c in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/f132c6a30455a7c0e6f26a92a2f9a0d3 in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/e4c1706d3c2c3c6032bade10c4db8a95 in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/8fef69ba55182e2a254606cfaddd244f in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/61bd6b315384556ef9a85c6e3bae5bb1 in service volusonic
Apr 20 12:43:10 volumio volumio[821]: info: Exploding uri volusonic/track/a328bdb8e90a29929ce59994dea3606c in service volusonic
Apr 20 12:43:16 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::ClearQueue
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrackBlock
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 1
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::serviceStop
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 1
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::serviceStop
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796657] ControllerVolusonic::stop
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::stop
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::addQueueItems
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::addQueueItems
Apr 20 12:43:16 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:16 volumio volumio[821]: info: Adding Item to queue: volusonic/random/8863c3d19b7f1b8709052fb6b21e5c67
Apr 20 12:43:16 volumio volumio[821]: info: Exploding uri volusonic/random/8863c3d19b7f1b8709052fb6b21e5c67 in service volusonic
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrackBlock
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPlay
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::play index 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::play index undefined
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796691] ControllerVolusonic::clearAddPlayTrack
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop
Apr 20 12:43:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand stop took 124 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796786] ControllerVolusonic: MPD player state update
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand stop took 98 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 5 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:16 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 13 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 12 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand clear took 10 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 10 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: error: updateQueue error: null
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 13ms
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 30ms
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:16 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic" took 2 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 5ms
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand play took 4 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796839] ControllerVolusonic::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:16 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796898] ControllerVolusonic: MPD player state update
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 7 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 9 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 5 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796926] ControllerVolusonic::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:16 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:43:16 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:16 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 49ms
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 45ms
Apr 20 12:43:16 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue
Apr 20 12:43:16 volumio volumio[821]: info: CoreStateMachine::getQueue
Apr 20 12:43:16 volumio volumio[821]: info: CorePlayQueue::getQueue
Apr 20 12:43:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:16 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:16 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:16 volumio volumio[821]: info: [1713609796992] ControllerVolusonic: MPD player state update
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info:
Apr 20 12:43:16 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:16 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:16 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:16 volumio volumio[821]: info: sendMpdCommand status took 5 milliseconds
Apr 20 12:43:16 volumio volumio[821]: info: ------------------------------ 7ms
Apr 20 12:43:17 volumio volumio[821]: info: sendMpdCommand status took 4 milliseconds
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 4 milliseconds
Apr 20 12:43:17 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:17 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:17 volumio volumio[821]: info: [1713609797009] ControllerVolusonic::pushState
Apr 20 12:43:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:17 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":175,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Guignolo Romantique","artist":"Philippe Sarde","album":"Le Guignolo","uri":"http://192.168.1.250:4533/rest/stream.view?id=1ef69b9bf5d50162e6947ad9b827b4eb&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:17 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:17 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:17 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:43:17 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:43:17 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:17 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:17 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:17 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:17 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:17 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:17 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:17 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:17 volumio volumio[821]: info: ------------------------------ 45ms
Apr 20 12:43:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:17 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:35 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri
Apr 20 12:43:35 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/260cef922ae3604a72cd7440024dd562
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/628513b8ca5e73f77d074898145174d1
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/ad6e210b1e518f83b2c05ca66c001d2e
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/4c568f0080828ee22df6544efd8a4c5b
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/168900b4a4297a966968376e716d0736
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/9232bb27538ad6c5208605d638664180
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/502e6b6bcdf07f887a05d8c273124e1b
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/3b3f54fb1c027e4090463390cfb55947
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/369ba5d91cc7197c1da134203079356d
Apr 20 12:43:35 volumio volumio[821]: info: Preloading song: volusonic/track/b0af9126027b651daaf3138c87bdbfd8
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/260cef922ae3604a72cd7440024dd562 in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/628513b8ca5e73f77d074898145174d1 in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/ad6e210b1e518f83b2c05ca66c001d2e in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: No valid Plugin REST Endpoint
Apr 20 12:43:35 volumio volumio[821]: info: No valid Plugin REST Endpoint
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/4c568f0080828ee22df6544efd8a4c5b in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/168900b4a4297a966968376e716d0736 in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/9232bb27538ad6c5208605d638664180 in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/502e6b6bcdf07f887a05d8c273124e1b in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/3b3f54fb1c027e4090463390cfb55947 in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/369ba5d91cc7197c1da134203079356d in service volusonic
Apr 20 12:43:35 volumio volumio[821]: info: Exploding uri volusonic/track/b0af9126027b651daaf3138c87bdbfd8 in service volusonic
Apr 20 12:43:42 volumio kernel: hwmon hwmon1: Voltage normalised
Apr 20 12:43:43 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::ClearQueue
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::stPlaybackTimer
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrackBlock
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::serviceStop
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::serviceStop
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823117] ControllerVolusonic::stop
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::stop
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::clearPlayQueue
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::addQueueItems
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::addQueueItems
Apr 20 12:43:43 volumio volumio[821]: info: Preload queue cleared
Apr 20 12:43:43 volumio volumio[821]: info: Adding Item to queue: volusonic/random/a670812dea1a4f0475c79349324a4346
Apr 20 12:43:43 volumio volumio[821]: info: Exploding uri volusonic/random/a670812dea1a4f0475c79349324a4346 in service volusonic
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushQueue
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::saveQueue
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::updateTrackBlock
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrackBlock
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPlay
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::play index 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::stop
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::play index undefined
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::startPlaybackTimer
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823135] ControllerVolusonic::clearAddPlayTrack
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand stop
Apr 20 12:43:43 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand stop took 94 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823213] ControllerVolusonic: MPD player state update
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand stop took 79 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand clear
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:43 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 4 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand clear took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: error: updateQueue error: null
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 5ms
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 11ms
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic"
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:43 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand add "http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic" took 1 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand play
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 3ms
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand play took 2 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 2 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823262] ControllerVolusonic::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:43 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus stop
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823315] ControllerVolusonic: MPD player state update
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 5 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 13 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 6 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823336] ControllerVolusonic::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:43 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:43:43 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces system playlist update
Apr 20 12:43:43 volumio volumio[821]: info: Ignoring MPD Status Update
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823381] ControllerVolusonic: MPD player state update
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: info:
Apr 20 12:43:43 volumio volumio[821]: ---------------------------- MPD announces state update: player
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 52 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 49 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::getState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand status
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 71ms
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioGetQueue
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::getQueue
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getQueue
Apr 20 12:43:43 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:43 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 50 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 54ms
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand status took 51 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 51 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseState
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 118ms
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 20 12:43:43 volumio volumio[821]: info: sendMpdCommand playlistinfo took 8 milliseconds
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: verbose: ControllerMpd::parseTrackInfo
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: [1713609823450] ControllerVolusonic::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":204,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Je suis venu te dire que je m'en vais","artist":"Serge Gainsbourg","album":"Vu de l'extérieur","uri":"http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic","trackType":""}
Apr 20 12:43:43 volumio volumio[821]: verbose: CURRENT POSITION 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState stateService play
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::syncState currentStatus play
Apr 20 12:43:43 volumio volumio[821]: info: Received an update from plugin. extracting info from payload
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreStateMachine::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::volumioPushState
Apr 20 12:43:43 volumio volumio[821]: info: ControllerMpd::pushState
Apr 20 12:43:43 volumio volumio[821]: info: CoreCommandRouter::servicePushState
Apr 20 12:43:43 volumio volumio[821]: info: CorePlayQueue::getTrack 0
Apr 20 12:43:43 volumio volumio[821]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd
Apr 20 12:43:43 volumio volumio[821]: info: ------------------------------ 97ms
Apr 20 12:43:43 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:43 volumio volumio[821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52
Apr 20 12:43:44 volumio kernel: hwmon hwmon1: Undervoltage detected!
Apr 20 12:43:49 volumio volumio[821]: info: Adding http://192.168.1.250:4533/rest/stream.view?id=260cef922ae3604a72cd7440024dd562&format=raw&u=Player&p=enc:5578544a3523384d7077463842&v=1.10.2&f=json&c=volusonic to favourites with specific volusonic method
Apr 20 12:43:49 volumio volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 12:43:49 volumio volumio[821]: TypeError: Cannot read property 'then' of undefined
Apr 20 12:43:49 volumio volumio[821]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:651:20)
Apr 20 12:43:49 volumio volumio[821]: at Socket.emit (events.js:315:20)
Apr 20 12:43:49 volumio volumio[821]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Apr 20 12:43:49 volumio volumio[821]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Apr 20 12:43:49 volumio volumio[821]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 12:43:50 volumio sudo[13486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 12:42
Apr 20 12:43:50 volumio sudo[13486]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST"
VOLUMIO_VERSION="3.569"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"