-- 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"