-- Logs begin at Thu 2025-05-15 17:24:58 CEST, end at Thu 2025-05-15 21:34:19 CEST. -- May 15 21:33:02 rivo volumio[3310]: verbose: New Socket.io Connection to 192.168.1.105:3000 from 192.168.1.104 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 15 21:33:13 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:33:13 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:33:13 rivo volumio[3310]: info: Discovery: Getting this device information May 15 21:33:13 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:33:13 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:13 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:33:32 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:33:32 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:33:32 rivo volumio[3310]: info: Discovery: Getting this device information May 15 21:33:32 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:33:32 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:32 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:33:40 rivo volumio[3310]: verbose: New Socket.io Connection to 192.168.1.105:3000 from 192.168.1.104 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 15 21:33:51 rivo volumio[3310]: info: [1747337631084] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 May 15 21:33:51 rivo volumio[3310]: info: [1747337631983] [RadioParadise] received new metadata: {"time":194,"artist":"J.S. Ondara","title":"Lebanon","album":"Tales of America","year":"2019","cover":"https://img.radioparadise.com/covers/l/B07HSKMP26.jpg","cover_med":"https://img.radioparadise.com/covers/m/B07HSKMP26.jpg","cover_small":"https://img.radioparadise.com/covers/s/B07HSKMP26.jpg"} May 15 21:33:51 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:51 rivo volumio[3310]: info: CoreCommandRouter::servicePushState May 15 21:33:51 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:51 rivo volumio[3310]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B07HSKMP26.jpg","uri":"https://stream.radioparadise.com/flac","name":"Lebanon","title":"Lebanon","artist":"J.S. Ondara","album":"Tales of America","streaming":true,"disableUiControls":true,"duration":199,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 15 21:33:51 rivo volumio[3310]: verbose: CURRENT POSITION 0 May 15 21:33:51 rivo volumio[3310]: info: CoreStateMachine::syncState stateService play May 15 21:33:51 rivo volumio[3310]: info: CoreStateMachine::syncState currentStatus play May 15 21:33:51 rivo volumio[3310]: info: Received an update from plugin. extracting info from payload May 15 21:33:51 rivo volumio[3310]: info: CoreStateMachine::pushState May 15 21:33:51 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:51 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:33:51 rivo volumio[3310]: info: CoreCommandRouter::volumioPushState May 15 21:33:51 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:33:52 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:52 rivo volumio[3310]: info: CoreStateMachine::pushState May 15 21:33:52 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:52 rivo volumio[3310]: info: CoreCommandRouter::volumioPushState May 15 21:33:52 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:33:52 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:52 rivo volumio[3310]: info: [1747337632019] [RadioParadise] setting new timer with duration of 199000 seconds. May 15 21:33:52 rivo volumio[3310]: info: Signalling Playback active due to playback status change May 15 21:33:52 rivo volumio[3310]: info: Signalling Playback active due to playback status change May 15 21:33:52 rivo volumio[3310]: info: [LastFM] Current track has sufficient metadata: title (Lebanon) and artist (J.S. Ondara) passed on explicitly May 15 21:33:52 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:33:52 rivo volumio[3310]: info: CorePlayQueue::getTrack 1 May 15 21:33:52 rivo volumio[3310]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Lebanon","url":"https://www.last.fm/music/J.S.+Ondara/_/Lebanon","duration":"206000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"10135","playcount":"33428","artist":{"name":"J.S. Ondara","url":"https://www.last.fm/music/J.S.+Ondara"},"album":{"artist":"J.S. Ondara","title":"Lebanon","url":"https://www.last.fm/music/J.S.+Ondara/Lebanon","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/e3e6632f28d4d622a6e0e93a730e90cc.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/e3e6632f28d4d622a6e0e93a730e90cc.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/e3e6632f28d4d622a6e0e93a730e90cc.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/e3e6632f28d4d622a6e0e93a730e90cc.png","@":{"size":"extralarge"}}]},"userplaycount":"13","userloved":"0","toptags":{"tag":[{"name":"jazz fusion","url":"https://www.last.fm/tag/jazz+fusion"},{"name":"fip","url":"https://www.last.fm/tag/fip"}]}}} May 15 21:33:54 rivo volumio[3310]: info: Executing endpoint metavolumio May 15 21:33:54 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 15 21:33:54 rivo volumio[3310]: info: Executing endpoint metavolumio May 15 21:33:54 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 15 21:33:54 rivo volumio[3310]: info: Executing endpoint metavolumio May 15 21:33:54 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 15 21:34:10 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:34:10 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:34:10 rivo volumio[3310]: info: Discovery: Getting this device information May 15 21:34:10 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:34:10 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:10 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:34:12 rivo volumio[3310]: verbose: New Socket.io Connection to 192.168.1.105:3000 from 192.168.1.104 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 15 21:34:17 rivo volumio[3310]: info: [1747337657391] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 15 21:34:17 rivo volumio[3310]: info: [1747337657519] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 15 21:34:17 rivo volumio[3310]: info: [1747337657580] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 May 15 21:34:18 rivo volumio[3310]: info: [1747337658232] [RadioParadise] received new metadata: {"time":63,"artist":"","title":null,"album":null,"year":null,"cover":"https://img.radioparadise.com/covers/l/.jpg","cover_med":"https://img.radioparadise.com/covers/m/.jpg","cover_small":"https://img.radioparadise.com/covers/s/.jpg"} May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::servicePushState May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/.jpg","uri":"https://stream.radioparadise.com/flac","name":null,"title":null,"artist":"","album":null,"streaming":true,"disableUiControls":true,"duration":68,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 15 21:34:18 rivo volumio[3310]: verbose: CURRENT POSITION 0 May 15 21:34:18 rivo volumio[3310]: info: CoreStateMachine::syncState stateService play May 15 21:34:18 rivo volumio[3310]: info: CoreStateMachine::syncState currentStatus play May 15 21:34:18 rivo volumio[3310]: info: Received an update from plugin. extracting info from payload May 15 21:34:18 rivo volumio[3310]: info: CoreStateMachine::pushState May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::volumioPushState May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: info: CoreStateMachine::pushState May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::volumioPushState May 15 21:34:18 rivo volumio[3310]: info: CoreCommandRouter::volumioGetState May 15 21:34:18 rivo volumio[3310]: info: CorePlayQueue::getTrack 0 May 15 21:34:18 rivo volumio[3310]: info: [1747337658261] [RadioParadise] setting new timer with duration of 68000 seconds. May 15 21:34:18 rivo volumio[3310]: info: Signalling Playback active due to playback status change May 15 21:34:18 rivo volumio[3310]: info: Signalling Playback active due to playback status change May 15 21:34:18 rivo volumio[3310]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 15 21:34:18 rivo volumio[3310]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:34:18 rivo volumio[3310]: TypeError: Cannot read property 'indexOf' of null May 15 21:34:18 rivo volumio[3310]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 15 21:34:18 rivo volumio[3310]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 15 21:34:18 rivo volumio[3310]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 15 21:34:18 rivo volumio[3310]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 15 21:34:18 rivo volumio[3310]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 15 21:34:18 rivo volumio[3310]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 15 21:34:18 rivo volumio[3310]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 15 21:34:18 rivo volumio[3310]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 15 21:34:18 rivo volumio[3310]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 15 21:34:18 rivo volumio[3310]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 15 21:34:18 rivo volumio[3310]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 15 21:34:18 rivo volumio[3310]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 15 21:34:18 rivo volumio[3310]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 15 21:34:18 rivo volumio[3310]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 15 21:34:18 rivo volumio[3310]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 15 21:34:18 rivo volumio[3310]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 15 21:34:18 rivo volumio[3310]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:34:19 rivo sudo[7278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 21:33 May 15 21:34:19 rivo sudo[7278]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:35:56 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ea561adf1e965df757744ffb8ecc9b8a"