Jun 08 13:00:05 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:06 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:15 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:15 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:15 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:16 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:16 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:16 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 13:00:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 13:00:24 volumio volumio[1089]: info: Discovery: Getting this device information Jun 08 13:00:24 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 13:00:25 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:26 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:35 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:35 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:35 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:36 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:36 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:36 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:45 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:46 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:53 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 13:00:53 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:00:53 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 08 13:00:53 volumio dbus-daemon[596]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.106' (uid=0 pid=16180 comm="timedatectl show --property=NTPSynchronized --valu") Jun 08 13:00:53 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 08 13:00:53 volumio dbus-daemon[596]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 08 13:00:53 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 08 13:00:53 volumio setdatetime-helper.sh[16179]: Time is already synchronized. Jun 08 13:00:53 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 08 13:00:53 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 08 13:00:54 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 13:00:54 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 13:00:54 volumio volumio[1089]: info: Discovery: Getting this device information Jun 08 13:00:54 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:54 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 13:00:55 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:55 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:55 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:56 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:00:56 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:56 volumio volumio[1089]: info: Listing playlists Jun 08 13:00:58 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 08 13:00:58 volumio volumio[1089]: info: CURURI: albums:// Jun 08 13:00:58 volumio volumio[1089]: info: listAlbums - loading Albums from cache Jun 08 13:00:58 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:01:05 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:01:06 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:01:11 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 08 13:01:11 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:01:15 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:01:15 volumio volumio[1089]: info: Listing playlists Jun 08 13:01:15 volumio volumio[1089]: info: Listing playlists Jun 08 13:01:15 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 08 13:01:15 volumio volumio[1089]: info: TuneIn handleBrowseUri: tunein/popular Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:01:16 volumio volumio[1089]: info: Listing playlists Jun 08 13:01:16 volumio volumio[1089]: info: Listing playlists Jun 08 13:01:16 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:01:16 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::ClearQueue Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::stop Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::updateTrackBlock Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrackBlock Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::stPlaybackTimer Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrack 29 Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::serviceStop Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrack 29 Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::serviceStop Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 13:01:16 volumio volumio[1089]: info: ControllerMpd::stop Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::clearPlayQueue Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::saveQueue Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushQueue Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::addQueueItems Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::addQueueItems Jun 08 13:01:16 volumio volumio[1089]: info: Preload queue cleared Jun 08 13:01:16 volumio volumio[1089]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s8365 Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:16 volumio volumio[1089]: info: Jun 08 13:01:16 volumio volumio[1089]: ---------------------------- MPD announces state update: player Jun 08 13:01:16 volumio volumio[1089]: info: sendMpdCommand stop took 53 milliseconds Jun 08 13:01:16 volumio volumio[1089]: info: ControllerMpd::getState Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jun 08 13:01:16 volumio volumio[1089]: info: sendMpdCommand status took 3 milliseconds Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::parseState Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 13:01:16 volumio volumio[1089]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 08 13:01:16 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jun 08 13:01:16 volumio volumio[1089]: info: ControllerMpd::pushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrack 29 Jun 08 13:01:16 volumio volumio[1089]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beautiful, Dirty, Rich (Official Music Video)","artist":"Lady Gaga","album":null,"uri":"https://rr4---sn-25ge7nzs.googlevideo.com/videoplayback?expire=1749401940&ei=9GxFaIT3JMGY6dsPt9ObuQE&ip=82.66.174.155&id=o-AAdM5Kwl5Mspit5Cdq9eF1rKLX9KV2I7rll5i3ha3P_4&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749380340%2C&mh=T2&mm=31%2C26&mn=sn-25ge7nzs%2Csn-aigl6nz7&ms=au%2Conr&mv=m&mvi=4&pl=22&rms=au%2Cau&gcr=fr&initcwndbps=2966250&bui=AY1jyLNwNQLMm5slI4ID4gy0jDRZf-_rJ006ckFglq4KY2ARCpQOzpFlpcXmO6mS0onZ1EMQHkQXLBOe&spc=l3OVKfdbaeOCkQdoRz-jyffKSTN5Em9zoT2BFY0FZr83k6VCN0DmUzVuqAjEi0mEDWc&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=OEHeW3OGdUXih2M_IdpgeuUQ&rqh=1&gir=yes&clen=2689112&dur=172.241&lmt=1714521497434163&mt=1749380014&fvip=4&keepalive=yes&fexp=51476174&c=WEB_REMIX&sefc=1&txp=4502434&n=eKK6er1xhAac_A&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgJCE_OGvOyzhkdHSjE7UdcEg5ayjVXAqOXSX1-6gehY0CIQD7pXwiOzQxmPKxNsoHPSiTqD9H15E_3CePE5sWclgJww%3D%3D&sig=AJfQdSswRAIgDY2RRQ6MrqGCqyc1r--NIsJmNufCtv0u_VvntiakBbUCIAValvyHo02R1Gkrqi3rhV2TURZaBHyaLVtOLRi93rSG&pot=MpgBSIRasu8HpBlIuDOM5Z9ixsQNs2PW8Cb7DKyfVN2m2P7o_WxJAOwACg8P2vpeoT25FGulhOiCjak7-_6jt_Dk2vtPPW9xSSi4yE0ElBJVWBarwXzTyhMzTLhlhotVYKhVc50um2L5MH6VPg-tUFSHqDZb-lCXj5kfl_3G2p1cvM2dHEB8VGYbm8evV5Xzb_9ojCulKTUnorY%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 08 13:01:16 volumio volumio[1089]: verbose: CURRENT POSITION 29 Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::syncState stateService stop Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:16 volumio volumio[1089]: info: No code Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:16 volumio volumio[1089]: info: ------------------------------ 32ms Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:16 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:16 volumio volumio[1089]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 08 13:01:16 volumio volumio[1089]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 08 13:01:16 volumio volumio[1089]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPushQueue Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::saveQueue Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::updateTrackBlock Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrackBlock Jun 08 13:01:16 volumio volumio[1089]: info: CoreCommandRouter::volumioPlay Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::play index 0 Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::stop Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::play index undefined Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Jun 08 13:01:16 volumio volumio[1089]: info: CoreStateMachine::startPlaybackTimer Jun 08 13:01:16 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Jun 08 13:01:16 volumio volumio[1089]: info: [1749380476666] ControllerWebradio::clearAddPlayTrack Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 13:01:16 volumio volumio[1089]: info: sendMpdCommand stop took 2 milliseconds Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 13:01:16 volumio volumio[1089]: info: Jun 08 13:01:16 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Jun 08 13:01:16 volumio volumio[1089]: info: Ignoring MPD Status Update Jun 08 13:01:16 volumio volumio[1089]: info: sendMpdCommand clear took 1 milliseconds Jun 08 13:01:16 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand load "https://stream.rcs.revma.com/b90ufxm1am3vv" Jun 08 13:01:16 volumio volumio[1089]: error: updateQueue error: null Jun 08 13:01:16 volumio volumio[1089]: info: ------------------------------ 1ms Jun 08 13:01:17 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand add "https://stream.rcs.revma.com/b90ufxm1am3vv" Jun 08 13:01:17 volumio volumio[1089]: info: Jun 08 13:01:17 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Jun 08 13:01:17 volumio volumio[1089]: info: Ignoring MPD Status Update Jun 08 13:01:17 volumio volumio[1089]: info: sendMpdCommand add "https://stream.rcs.revma.com/b90ufxm1am3vv" took 2 milliseconds Jun 08 13:01:17 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 13:01:17 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand play Jun 08 13:01:17 volumio volumio[1089]: info: ------------------------------ 4ms Jun 08 13:01:17 volumio volumio[1089]: info: sendMpdCommand play took 2 milliseconds Jun 08 13:01:18 volumio volumio[1089]: info: Jun 08 13:01:18 volumio volumio[1089]: ---------------------------- MPD announces state update: player Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::getState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 08 13:01:18 volumio volumio[1089]: info: camilladsp stopping service pid 15852... Jun 08 13:01:18 volumio volumio[1089]: info: camilladsp service terminated, instance 1 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:18 volumio volumio[1089]: info: camilladsp service started and running in background, instance 1 Jun 08 13:01:18 volumio volumio[1089]: info: Jun 08 13:01:18 volumio volumio[1089]: ---------------------------- MPD announces state update: player Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand status took 38 milliseconds Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::getState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 13:01:18 volumio volumio[1089]: info: Jun 08 13:01:18 volumio volumio[1089]: ---------------------------- MPD announces system playlist update Jun 08 13:01:18 volumio volumio[1089]: info: Ignoring MPD Status Update Jun 08 13:01:18 volumio volumio[1089]: info: Jun 08 13:01:18 volumio volumio[1089]: ---------------------------- MPD announces state update: player Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::getState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand status Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand status took 13 milliseconds Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 08 13:01:18 volumio volumio[1089]: info: ------------------------------ 12ms Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand status took 11 milliseconds Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseState Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Jun 08 13:01:18 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Jun 08 13:01:18 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ALANIS MORISSETTE - Ironic","artist":"-","album":null,"uri":"https://stream.rcs.revma.com/b90ufxm1am3vv","trackType":""} Jun 08 13:01:18 volumio volumio[1089]: verbose: CURRENT POSITION 0 Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus stop Jun 08 13:01:18 volumio volumio[1089]: info: ------------------------------ 58ms Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 08 13:01:18 volumio volumio[1089]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jun 08 13:01:18 volumio volumio[1089]: verbose: ControllerMpd::parseTrackInfo Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Jun 08 13:01:18 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Jun 08 13:01:18 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ALANIS MORISSETTE - Ironic","artist":"-","album":null,"uri":"https://stream.rcs.revma.com/b90ufxm1am3vv","trackType":""} Jun 08 13:01:18 volumio volumio[1089]: verbose: CURRENT POSITION 0 Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jun 08 13:01:18 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:18 volumio volumio[1089]: info: ControllerMpd::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::servicePushState Jun 08 13:01:18 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Jun 08 13:01:18 volumio volumio[1089]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ALANIS MORISSETTE - Ironic","artist":"-","album":null,"uri":"https://stream.rcs.revma.com/b90ufxm1am3vv","trackType":""} Jun 08 13:01:18 volumio volumio[1089]: verbose: CURRENT POSITION 0 Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState stateService play Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::syncState currentStatus play Jun 08 13:01:18 volumio volumio[1089]: info: Received an update from plugin. extracting info from payload Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreStateMachine::pushState Jun 08 13:01:18 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Jun 08 13:01:18 volumio volumio[1089]: info: ------------------------------ 54ms Jun 08 13:01:18 volumio volumio[1089]: info: ------------------------------ 53ms Jun 08 13:01:18 volumio volumio[1089]: info: [LastFM] Split composite title into artist: ALANIS MORISSETTE and title: Ironic Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - volume level for loudness 37 gain applied 6.64 Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - File size found in array! Jun 08 13:01:18 volumio volumio[1089]: info: FusionDsp - Loudness is ON true Jun 08 13:01:18 volumio volumio[1089]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 08 13:01:19 volumio volumio[1089]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Ironic","mbid":"921dc61e-fba2-442b-a03a-ceb1c7798450","url":"https://www.last.fm/music/Alanis+Morissette/_/Ironic","duration":"225000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"850149","playcount":"4912077","artist":{"name":"Alanis Morissette","mbid":"4bdcee62-4902-4773-8cd1-e252e2e31225","url":"https://www.last.fm/music/Alanis+Morissette"},"album":{"@":{"position":"10"},"artist":"Alanis Morissette","title":"Jagged Little Pill","mbid":"06bb9094-75f1-4eee-a960-d5d90e5d0987","url":"https://www.last.fm/music/Alanis+Morissette/Jagged+Little+Pill","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/0d6e35eaf31f4e1db42b2ea247d90bb7.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/0d6e35eaf31f4e1db42b2ea247d90bb7.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/0d6e35eaf31f4e1db42b2ea247d90bb7.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/0d6e35eaf31f4e1db42b2ea247d90bb7.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":{"tag":[{"name":"rock","url":"https://www.last.fm/tag/rock"},{"name":"female vocalists","url":"https://www.last.fm/tag/female+vocalists"},{"name":"90s","url":"https://www.last.fm/tag/90s"},{"name":"alternative","url":"https://www.last.fm/tag/alternative"},{"name":"pop","url":"https://www.last.fm/tag/pop"}]},"wiki":{"published":"05 Mar 2009, 05:36","summary":"\"Ironic\" is a song written by Alanis Morissette and Glen Ballard and produced by Ballard for Morissette's third album Jagged Little Pill (1995). It was released as the album's fourth single in 1996. Musically, the song is a glossy take on a basic grunge music format with delicate, sparsely-instrumented verses alternating with loud, aggressive chorus sections. Lyrically, it comprises a series of vignettes describing situations that end with the question \"Isn't it ironic?\" Read more on Last.fm.","content":"\"Ironic\" is a song written by Alanis Morissette and Glen Ballard and produced by Ballard for Morissette's third album Jagged Little Pill (1995). It was released as the album's fourth single in 1996. Musically, the song is a glossy take on a basic grunge music format with delicate, sparsely-instrumented verses alternating with loud, aggressive chorus sections. Lyrically, it comprises a series of vignettes describing situations that end with the question \"Isn't it ironic?\" Because \"Ironic\" was Morissette's first single to be given a commercial release in the United States, it was eligible to appear on the Billboard Hot 100 chart and reached number four. As of 2008, it was her highest-peaking single in the US. \"Ironic\" became Morissette's third number-one hit on Billboard's Modern Rock Tracks chart and her first on the Top 40 Mainstream chart and received frequent rotation on Adult Top 40 radio stations. It reached the Top Ten in Germany and peaked just outside the Top Ten in the United Kingdom, becoming Morissette's first top twenty hit there. \"Ironic\", which contains a reference to a plane crash (...\"and as the plane crashed down, he thought, 'well, isn't this nice?'\"), was on the list of songs deemed inappropriate by Clear Channel following the September 11, 2001 attacks, and subsequently left off the set list during her 2002 tour. \"Ironic\" was also recently made popular by the 90 second version arranged by Andrew \"Flanders\" Ryan, for the dashing A Cappella Choir \"Harambee\" in the 2008 edition of the Australian \"Battle of the Choirs\". The song's usage of the word \"ironic\" attracted attention for what many feel is an improper application of the term.Many situations that Morissette describes in the song are arguably examples of cosmic irony: events that, as the Oxford English Dictionary puts it, appear \"as if in mockery of the fitness or rightness of things\", such as \"a death row pardon/two minutes too late\". Morissette has also confirmed that she is a self-dubbed \"malapropism queen\" and alleges that the song was lighthearted and not taken too seriously at the time it was written: \"For me the great debate on whether what I was saying in ‘Ironic’ was ironic wasn’t a traumatic debate. I’d always embraced the fact that every once in a while I’d be the malapropism queen. And when Glen and I were writing it, we definitely were not doggedly making sure that everything was technically ironic. It’s a testament to the fact that we didn’t think it was going to be put under the microscope by 30 million people. For me the sweetest moment came in New York when a woman came up to me in a record store and said, ‘So all those things in the “Ironic” aren’t ironic.’ And then she said, ‘And that’s the irony.’ I said, ‘Yup.’ To me it’s a real snapshot of a nineteen-year-old’s definition and version of how life worked at the time. All that ‘Ironic’ touches on spawned all my future inquiries into and current understandings of the mysteries of life.\" The popular Irish comedian Ed Byrne always performs a skit in which he jokingly attacks the song for its lack of ironies: \"The only ironic thing about that song is it's called ironic and it's written by a woman who doesn't know what irony is. That's quite ironic.\" Byrne goes through the song, working out how to change the various unfortunate incidents, mentioned in the song, into ironies. Popular satirists Berger and Wyse also parodied the song in their cartoon strip The Pitchers. In an episode where superhero Irony Man (a pun on Iron Man) likens his superpowers to lyrics from Morrisette's song, causing his cohorts to rename him \"The Man from Alanis\" (a pun on The Man from Atlantis). In 2004 Morissette amended a lyric as a show of her support for same-sex marriage: \"It's meeting the man of my dreams And then meeting his beautiful husband\" She first sang the line at the fifteenth annual GLAAD Media Awards in March 2004. She recorded an acoustic version of the song with the amended lyric for an exclusive iTunes Music Store release. Another acoustic version was recorded for the album Jagged Little Pill Acoustic (2005), and another for the Cities 97 Sampler Volume 16 (2004). A live version without the altered lyric is included on the album Alanis Unplugged (1999). The song was also performed in a duo with Avril Lavigne, at the House of Blues, in 2005. The single's video was released in January 1996 and received heavy rotation on MTV and VH1 in the US. It was directed by Stéphane Sednaoui and features Morissette driving a black Lincoln Continental Mark V through a winter landscape. She also plays her passengers: one in a green sweater riding in the back seat; one in a yellow sweater with braided hair, also in the back seat; and one in a red sweater in the front passenger seat. At the end of the video, the car stalls and Morissette, as the driver, exits but her passengers are nowhere to be seen. However, a man then appears and asks them a question about the driver. (Not all versions contain the last scene.) Read more on Last.fm. User-contributed text is available under the Creative Commons By-SA License; additional terms may apply."}}} Jun 08 13:01:19 volumio volumio[1089]: info: [LastFM] Updated missing track duration: 225000 Jun 08 13:01:19 volumio volumio[1089]: info: [LastFM] Updated missing track album: Jagged Little Pill Jun 08 13:01:23 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 08 13:01:24 volumio volumio[1089]: Searching plugin music_service/webradio Jun 08 13:01:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 08 13:01:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 13:01:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 13:01:24 volumio volumio[1089]: info: Discovery: Getting this device information Jun 08 13:01:24 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Jun 08 13:01:24 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 13:01:24 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 13:01:24 volumio volumio[1089]: TypeError: Cannot read properties of undefined (reading 'length') Jun 08 13:01:24 volumio volumio[1089]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Jun 08 13:01:24 volumio volumio[1089]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Jun 08 13:01:24 volumio volumio[1089]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Jun 08 13:01:24 volumio volumio[1089]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 08 13:01:24 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 13:01:24 volumio sudo[16357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-08 13:00' Jun 08 13:01:24 volumio sudo[16357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"