-- Logs begin at Mon 2025-01-20 08:55:39 UTC, end at Mon 2025-01-20 08:57:33 UTC. -- Jan 20 08:56:17 volumio-home systemd[1]: Time has been changed Jan 20 08:56:19 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:19 volumio-home volumio[1057]: info: ----- Volumio2 ---- Jan 20 08:56:19 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:19 volumio-home volumio[1057]: info: ----- System startup ---- Jan 20 08:56:19 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:20 volumio-home volumio[1057]: info: Plugin folders cleanup Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning into folder /volumio/app/plugins/ Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category audio_interface Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category miscellanea Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category music_service Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category plugins.json Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category system_controller Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category user_interface Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning into folder /data/plugins/ Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category miscellanea Jan 20 08:56:20 volumio-home volumio[1057]: info: Scanning category music_service Jan 20 08:56:20 volumio-home volumio[1057]: info: Plugin folders cleanup completed Jan 20 08:56:20 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:20 volumio-home volumio[1057]: info: ----- Core plugins startup ---- Jan 20 08:56:20 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:20 volumio-home volumio[1057]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 08:56:20 volumio-home volumio[1057]: info: Loading plugins from folder /data/plugins/ Jan 20 08:56:20 volumio-home volumio[1057]: info: Loading plugin "system"... Jan 20 08:56:20 volumio-home volumio[1057]: info: Loading plugin "appearance"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "network"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "services"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "alsa_controller"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "wizard"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "volumio_command_line_client"... Jan 20 08:56:21 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:21 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:21 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:21 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "upnp"... Jan 20 08:56:21 volumio-home volumio[1057]: info: [1737363381686] Starting Upmpd Daemon Jan 20 08:56:21 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "my_music"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "mpd"... Jan 20 08:56:21 volumio-home volumio[1057]: info: Loading plugin "upnp_browser"... Jan 20 08:56:21 volumio-home kernel: Voltage normalised (0x00000000) Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "networkfs"... Jan 20 08:56:23 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "alarm-clock"... Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "airplay_emulation"... Jan 20 08:56:23 volumio-home volumio[1057]: info: [1737363383742] Starting Shairport Sync Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "last_100"... Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "webradio"... Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "i2s_dacs"... Jan 20 08:56:23 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:56:23 volumio-home volumio[1057]: info: Loading plugin "volumiodiscovery"... Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** For more information see Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:56:23 volumio-home node[1057]: *** WARNING *** For more information see Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** For more information see Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 08:56:23 volumio-home volumio[1057]: *** WARNING *** For more information see Jan 20 08:56:23 volumio-home volumio[1057]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 08:56:23 volumio-home volumio[1057]: Discovery: StartAdv! undefined Jan 20 08:56:23 volumio-home volumio[1057]: Discovery: Started advertising... Volumio-home - undefined Jan 20 08:56:24 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 08:56:24 volumio-home kernel: Under-voltage detected! (0x00050005) Jan 20 08:56:24 volumio-home volumio[1057]: info: Loading plugin "autostart"... Jan 20 08:56:24 volumio-home volumio[1057]: info: Applying required configuration parameters for plugin autostart Jan 20 08:56:24 volumio-home volumio[1057]: info: Loading plugin "youtube"... Jan 20 08:56:25 volumio-home volumio-remote-updater[533]: [2025-01-20 08:56:25] [connect] Successful connection Jan 20 08:56:26 volumio-home volumio[1057]: info: Loading plugin "albumart"... Jan 20 08:56:26 volumio-home volumio[1057]: info: Plugin example_plugin is not enabled Jan 20 08:56:26 volumio-home volumio[1057]: info: Loading plugin "updater_comm"... Jan 20 08:56:26 volumio-home volumio[1057]: info: Plugin mpdemulation is not enabled Jan 20 08:56:26 volumio-home volumio[1057]: info: Loading plugin "rest_api"... Jan 20 08:56:26 volumio-home volumio[1057]: info: Loading plugin "websocket"... Jan 20 08:56:26 volumio-home volumio[1057]: info: Loading plugin "lastfm"... Jan 20 08:56:27 volumio-home volumio[1057]: Forking 3 albumart workers Jan 20 08:56:28 volumio-home volumio[1057]: Starting albumart workers Jan 20 08:56:28 volumio-home volumio[1057]: Starting albumart workers Jan 20 08:56:28 volumio-home volumio[1057]: Starting albumart workers Jan 20 08:56:28 volumio-home volumio[1057]: info: [LastFM] scrobbler initiated! Jan 20 08:56:28 volumio-home volumio[1057]: info: [LastFM] extended logging: false Jan 20 08:56:28 volumio-home volumio[1057]: info: [LastFM] try scrobble stream/radio plays: true Jan 20 08:56:28 volumio-home volumio[1057]: info: Loading plugin "minidlna"... Jan 20 08:56:29 volumio-home volumio[1057]: info: Applying required configuration parameters for plugin minidlna Jan 20 08:56:29 volumio-home volumio[1057]: info: Loading plugin "personal_radio"... Jan 20 08:56:31 volumio-home volumio[1057]: info: ControllerPersonalRadio::constructor Jan 20 08:56:31 volumio-home volumio[1057]: info: ___________ START PLUGINS ___________ Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391194] CoreMusicLibrary::Adding element Media Servers Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391208] CoreMusicLibrary::Adding element Last_100 Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391211] CoreMusicLibrary::Adding element Webradio Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 08:56:31 volumio-home volumio[1057]: info: Youtube::onStart Adding to browse sources Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391216] CoreMusicLibrary::Adding element Youtube Jan 20 08:56:31 volumio-home volumio[1057]: info: Performing onStart action Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391219] CoreMusicLibrary::Adding element LastFM Jan 20 08:56:31 volumio-home volumio[1057]: info: Loading i18n strings for locale ko Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 08:56:31 volumio-home volumio[1057]: info: [1737363391267] CoreMusicLibrary::Adding element 개인 라디오 Jan 20 08:56:31 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:31 volumio-home volumio[1057]: info: ----- MyVolumio plugins startup ---- Jan 20 08:56:31 volumio-home volumio[1057]: info: ------------------------------------------- Jan 20 08:56:31 volumio-home volumio[1057]: info: Loading plugins from folder /myvolumio/plugins Jan 20 08:56:31 volumio-home volumio[1057]: info: Loading plugins from folder /data/myvolumio/plugins Jan 20 08:56:31 volumio-home volumio[1057]: info: Loading i18n strings for locale ko Jan 20 08:56:31 volumio-home volumio[1057]: Updating browse sources language Jan 20 08:56:31 volumio-home volumio[1057]: Cannot find translation for sourceMedia Servers Jan 20 08:56:31 volumio-home volumio[1057]: Cannot find translation for sourceYoutube Jan 20 08:56:31 volumio-home volumio[1057]: Cannot find translation for sourceLastFM Jan 20 08:56:31 volumio-home volumio[1057]: Cannot find translation for source개인 라디오 Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::initPlayerControls Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 08:56:31 volumio-home volumio[1057]: info: BOOT COMPLETED Jan 20 08:56:31 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:56:34 volumio-home kernel: Voltage normalised (0x00000000) Jan 20 08:56:36 volumio-home volumio[1057]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreCommandRouter::Close All Modals sent Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreCommandRouter::Close All Modals sent Jan 20 08:56:36 volumio-home volumio[1057]: Express server listening on port 3000 Jan 20 08:56:36 volumio-home volumio[1057]: Volumio Calling Home Jan 20 08:56:36 volumio-home volumio[1057]: info: Starting minidlna.service Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreStateMachine::resetVolumioState Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreStateMachine::getcurrentVolume Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 08:56:36 volumio-home sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 20 08:56:36 volumio-home volumio[1057]: info: CoreStateMachine::updateTrackBlock Jan 20 08:56:36 volumio-home volumio[1057]: info: CorePlayQueue::getTrackBlock Jan 20 08:56:36 volumio-home sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:56:36 volumio-home systemd[1]: Starting MiniDLNA UPnP-A/V and DLNA media server... Jan 20 08:56:36 volumio-home systemd[1]: Started MiniDLNA UPnP-A/V and DLNA media server. Jan 20 08:56:36 volumio-home sudo[1116]: pam_unix(sudo:session): session closed for user root Jan 20 08:56:37 volumio-home volumio[1057]: info: Setting Device type: Raspberry PI Jan 20 08:56:37 volumio-home volumio[1057]: info: MPD running with PID572 ,establishing connection Jan 20 08:56:37 volumio-home volumio[1057]: info: VolumeController:: Volume=undefined Mute =undefined Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:37 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:37 volumio-home volumio[1057]: info: start of minidlna.service succeeded. Jan 20 08:56:37 volumio-home volumio[1057]: info: Jan 20 08:56:37 volumio-home volumio[1057]: ---------------------------- Client requests Volume 40 Jan 20 08:56:37 volumio-home volumio[1057]: info: VolumeController::SetAlsaVolume40 Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 20 08:56:37 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 20 08:56:38 volumio-home volumio[1057]: info: Reloading queue from file Jan 20 08:56:38 volumio-home volumio[1057]: info: Jan 20 08:56:38 volumio-home volumio[1057]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jan 20 08:56:38 volumio-home volumio[1057]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jan 20 08:56:38 volumio-home volumio[1057]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jan 20 08:56:38 volumio-home volumio[1057]: at emitOne (events.js:116:13) Jan 20 08:56:38 volumio-home volumio[1057]: at Socket.emit (events.js:211:7) Jan 20 08:56:38 volumio-home volumio[1057]: at addChunk (_stream_readable.js:263:12) Jan 20 08:56:38 volumio-home volumio[1057]: at readableAddChunk (_stream_readable.js:246:13) Jan 20 08:56:38 volumio-home volumio[1057]: at Socket.Readable.push (_stream_readable.js:208:10) Jan 20 08:56:38 volumio-home volumio[1057]: at Pipe.onread (net.js:607:20) Jan 20 08:56:38 volumio-home volumio[1057]: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jan 20 08:56:38 volumio-home volumio[1057]: at Socket. (/volumio/app/volumecontrol.js:72:10) Jan 20 08:56:38 volumio-home volumio[1057]: at emitOne (events.js:116:13) Jan 20 08:56:38 volumio-home volumio[1057]: at Socket.emit (events.js:211:7) Jan 20 08:56:38 volumio-home volumio[1057]: at addChunk (_stream_readable.js:263:12) Jan 20 08:56:38 volumio-home volumio[1057]: at readableAddChunk (_stream_readable.js:250:11) Jan 20 08:56:38 volumio-home volumio[1057]: at Socket.Readable.push (_stream_readable.js:208:10) Jan 20 08:56:38 volumio-home volumio[1057]: at Pipe.onread (net.js:607:20) Jan 20 08:56:38 volumio-home volumio[1057]: info: VolumeController::Volume 40 Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:38 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:38 volumio-home volumio[1057]: info: Setting volume on startup at 40 Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::setRepeat null single undefined Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:38 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::setRandom false Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:38 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:38 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:38 volumio-home sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 08:56:38 volumio-home sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jan 20 08:56:38 volumio-home sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:56:38 volumio-home sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 08:56:38 volumio-home systemd[1]: Starting ShairportSync AirTunes receiver... Jan 20 08:56:38 volumio-home systemd[1]: Started ShairportSync AirTunes receiver. Jan 20 08:56:38 volumio-home systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 20 08:56:38 volumio-home systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 08:56:38 volumio-home sudo[1145]: pam_unix(sudo:session): session closed for user root Jan 20 08:56:38 volumio-home sudo[1143]: pam_unix(sudo:session): session closed for user root Jan 20 08:56:38 volumio-home kernel: Under-voltage detected! (0x00050005) Jan 20 08:56:38 volumio-home shairport-sync[1154]: Successful Startup Jan 20 08:56:38 volumio-home volumio[1057]: Discovery: adding 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jan 20 08:56:38 volumio-home volumio[1057]: info: mDNS: Found device Volumio-home Jan 20 08:56:38 volumio-home volumio[1057]: info: [1737363398879] Shairport-Sync Started Jan 20 08:56:38 volumio-home volumio[1057]: Error adding Membership: Error: addMembership EINVAL Jan 20 08:56:38 volumio-home volumio[1057]: info: Upmpdcli Daemon Started Jan 20 08:56:38 volumio-home volumio[1057]: Discovery: this is already registered, 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jan 20 08:56:38 volumio-home volumio[1057]: info: mDNS: Found device Volumio-home Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreCommandRouter::volumioGetState Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:39 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreCommandRouter::volumioGetState Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:39 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 08:56:39 volumio-home volumio[1057]: Plugin multiroom or method getMultiroom not found Jan 20 08:56:39 volumio-home volumio[1057]: info: Listing playlists Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreCommandRouter::volumioGetQueue Jan 20 08:56:39 volumio-home volumio[1057]: info: CoreStateMachine::getQueue Jan 20 08:56:39 volumio-home volumio[1057]: info: CorePlayQueue::getQueue Jan 20 08:56:39 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:40 volumio-home volumio-remote-updater[533]: [2025-01-20 08:56:40] [connect] Successful connection Jan 20 08:56:40 volumio-home volumio-remote-updater[533]: [2025-01-20 08:56:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1737363400 101 Jan 20 08:56:40 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:41 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:43 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:44 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:44 volumio-home volumio[1057]: info: AutoStart - getting queue Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreCommandRouter::volumioGetQueue Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::getQueue Jan 20 08:56:44 volumio-home volumio[1057]: info: CorePlayQueue::getQueue Jan 20 08:56:44 volumio-home volumio[1057]: info: AutoStart - start playing -> queue is not empty Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPlay Jan 20 08:56:44 volumio-home volumio[1057]: UNSET VOLATILE Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::play index undefined Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 08:56:44 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::startPlaybackTimer Jan 20 08:56:44 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand stop Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand clear Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: Jan 20 08:56:44 volumio-home volumio[1057]: ---------------------------- MPD announces system playlist update Jan 20 08:56:44 volumio-home volumio[1057]: info: Ignoring MPD Status Update Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand add "https://1fm-radio.gscdn.kbs.co.kr/1fm-b/1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: Jan 20 08:56:44 volumio-home volumio[1057]: info: Jan 20 08:56:44 volumio-home volumio[1057]: ---------------------------- MPD announces system playlist update Jan 20 08:56:44 volumio-home volumio[1057]: info: Ignoring MPD Status Update Jan 20 08:56:44 volumio-home volumio[1057]: info: ------------------------------ 16ms Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand play Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: ------------------------------ 20ms Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::getState Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand status Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::parseState Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 08:56:44 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:44 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:44 volumio-home volumio[1057]: info: ControllerMpd::parseTrackInfo Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::syncState Jan 20 08:56:44 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:44 volumio-home volumio[1057]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm-radio.gscdn.kbs.co.kr/1fm-b/1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jan 20 08:56:44 volumio-home volumio[1057]: info: CURRENT POSITION 0 Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::syncState stateService play Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::syncState currentStatus stop Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:44 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:44 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:44 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 08:56:44 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 08:56:44 volumio-home volumio[1057]: info: [LastFM] configuration error; "username" is not set. Jan 20 08:56:44 volumio-home volumio[1057]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 08:56:45 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:46 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:47 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:48 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:49 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:49 volumio-home volumio[1057]: info: Jan 20 08:56:49 volumio-home volumio[1057]: ---------------------------- MPD announces state update: player Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::getState Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand status Jan 20 08:56:49 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:49 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand clearerror Jan 20 08:56:49 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::parseState Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 08:56:49 volumio-home volumio[1057]: info: sending command... Jan 20 08:56:49 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:49 volumio-home volumio[1057]: info: parsing response... Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::parseTrackInfo Jan 20 08:56:49 volumio-home volumio[1057]: info: ControllerMpd::pushState Jan 20 08:56:49 volumio-home volumio[1057]: info: CoreCommandRouter::servicePushState Jan 20 08:56:50 volumio-home volumio[1057]: info: CoreStateMachine::syncState Jan 20 08:56:50 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:56:50 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:56:50 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:50 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:56:50 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:56:50 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:56:50 volumio-home volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jan 20 08:56:50 volumio-home volumio[1057]: info: ------------------------------ 76ms Jan 20 08:56:50 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 08:56:50 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 08:56:50 volumio-home volumio[1057]: info: [LastFM] configuration error; "username" is not set. Jan 20 08:56:50 volumio-home volumio[1057]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 08:56:50 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:51 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:52 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:52 volumio-home volumio[1057]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jan 20 08:56:52 volumio-home volumio[1057]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 20 08:56:52 volumio-home volumio[1057]: Dload Upload Total Spent Left Speed Jan 20 08:56:52 volumio-home volumio[1057]: [551B blob data] Jan 20 08:56:52 volumio-home volumio[1057]: retrying in 5 seconds, trial 0 Jan 20 08:56:52 volumio-home volumio[1057]: Volumio Calling Home Jan 20 08:56:53 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:54 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:55 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:56 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:57 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:58 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:56:59 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:00 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:01 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 08:57:01 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 08:57:01 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:02 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:03 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:04 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:05 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:06 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:07 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:08 volumio-home volumio[1057]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jan 20 08:57:08 volumio-home volumio[1057]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 20 08:57:08 volumio-home volumio[1057]: Dload Upload Total Spent Left Speed Jan 20 08:57:08 volumio-home volumio[1057]: [551B blob data] Jan 20 08:57:08 volumio-home volumio[1057]: retrying in 5 seconds, trial 1 Jan 20 08:57:08 volumio-home volumio[1057]: Volumio Calling Home Jan 20 08:57:08 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:09 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:10 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:11 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:12 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:13 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:14 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:15 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:16 volumio-home wpa_supplicant[943]: wlan0: CTRL-EVENT-DISCONNECTED bssid=80:ca:4b:37:a4:2f reason=0 locally_generated=1 Jan 20 08:57:16 volumio-home dhcpcd[939]: wlan0: carrier lost Jan 20 08:57:16 volumio-home dhcpcd[939]: eth0: adding route to 192.168.123.0/24 Jan 20 08:57:16 volumio-home dhcpcd[939]: eth0: adding default route via 192.168.123.1 Jan 20 08:57:16 volumio-home dhcpcd[939]: wlan0: deleting host route to 192.168.123.107 via 127.0.0.1 Jan 20 08:57:16 volumio-home dhcpcd[939]: wlan0: deleting route to 192.168.123.0/24 Jan 20 08:57:16 volumio-home dhcpcd[939]: wlan0: deleting host route to 192.168.1.1 Jan 20 08:57:16 volumio-home dhcpcd[939]: wlan0: deleting default route via 192.168.1.1 Jan 20 08:57:16 volumio-home avahi-daemon[570]: Withdrawing address record for 192.168.123.107 on wlan0. Jan 20 08:57:16 volumio-home avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.123.107. Jan 20 08:57:16 volumio-home avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 20 08:57:16 volumio-home wpa_supplicant[943]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 20 08:57:16 volumio-home volumio[1057]: info: mDNS: A device disapperared from network Jan 20 08:57:16 volumio-home volumio[1057]: info: mDNS: Device volumio-home disapperared from network Jan 20 08:57:16 volumio-home dhcpcd[939]: eth0: removing route to 192.168.123.0/24 Jan 20 08:57:16 volumio-home dhcpcd[939]: eth0: removing default route via 192.168.123.1 Jan 20 08:57:16 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:16 volumio-home wpa_supplicant[943]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Jan 20 08:57:17 volumio-home wpa_supplicant[943]: wlan0: Associated with 80:ca:4b:37:a4:2f Jan 20 08:57:17 volumio-home wpa_supplicant[943]: wlan0: WPA: Key negotiation completed with 80:ca:4b:37:a4:2f [PTK=CCMP GTK=CCMP] Jan 20 08:57:17 volumio-home wpa_supplicant[943]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:ca:4b:37:a4:2f completed [id=0 id_str=] Jan 20 08:57:17 volumio-home dhcpcd[939]: wlan0: carrier acquired Jan 20 08:57:17 volumio-home wpa_supplicant[943]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=KR Jan 20 08:57:17 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:18 volumio-home kernel: Voltage normalised (0x00000000) Jan 20 08:57:18 volumio-home ntpd[645]: Deleting interface #4 wlan0, 192.168.123.107#123, interface stats: received=0, sent=0, dropped=0, active_time=62 secs Jan 20 08:57:18 volumio-home ntpd[645]: peers refreshed Jan 20 08:57:18 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:19 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:21 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:22 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:23 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:24 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:24 volumio-home volumio[1057]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jan 20 08:57:24 volumio-home volumio[1057]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 20 08:57:24 volumio-home volumio[1057]: Dload Upload Total Spent Left Speed Jan 20 08:57:24 volumio-home volumio[1057]: [630B blob data] Jan 20 08:57:24 volumio-home volumio[1057]: retrying in 5 seconds, trial 2 Jan 20 08:57:24 volumio-home volumio[1057]: Volumio Calling Home Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPause Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::pause Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::stPlaybackTimer Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::servicePause Jan 20 08:57:24 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::servicePause Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::pause Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand pause Jan 20 08:57:24 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:24 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::getState Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand status Jan 20 08:57:24 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:24 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::parseState Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 08:57:24 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:24 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:24 volumio-home volumio[1057]: info: ControllerMpd::parseTrackInfo Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::syncState Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:57:24 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:57:24 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:24 volumio-home volumio[1057]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm-radio.gscdn.kbs.co.kr/1fm-b/1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jan 20 08:57:24 volumio-home volumio[1057]: info: CURRENT POSITION 0 Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::syncState stateService stop Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::syncState currentStatus pause Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:57:24 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:57:24 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:57:25 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPlay Jan 20 08:57:25 volumio-home volumio[1057]: UNSET VOLATILE Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::play index undefined Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::startPlaybackTimer Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::resume Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand play Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::getState Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand status Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::parseState Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::parseTrackInfo Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::syncState Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm-radio.gscdn.kbs.co.kr/1fm-b/1fm-b_192.m3u8?Expires=1608639583&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0tcmFkaW8uZ3NjZG4ua2JzLmNvLmtyLzFmbS1iLzFmbS1iXzE5Mi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNjA4NjM5NTgzfX19XX0_&Signature=JgnYw-SBNmxfxkJ0bkjkA0mRVgAWOLMOenZXfo3A1IWKBHObKOhhexHlRDsuWs7USbrclrrf7rgnzSQXyhbqxGKe45W0IFvwkKKHZDff9iSoBKFLZuOqrQTjz1R5PgdPauRfnqfujdQc1txh2GsI6JkQaNINU0SzUJQss889z6YEgvEtNQN5sBeRPZE9N-95Xs3SA~L6x2oWIc6NJMTcvzn-MsxfrvnIcnG8LSbUUiZf4jyymLxOpkaVjkT5b0a9ME4oGqEhLHbbEtEJMfADIMkNhi36R302w7bL0xQjWreGsf-kSlV61pqiYNfSUm5y0O4O9Vi1bgcTs9MvehTmXQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jan 20 08:57:25 volumio-home volumio[1057]: info: CURRENT POSITION 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::syncState stateService play Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::syncState currentStatus pause Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "username" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "username" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: Jan 20 08:57:25 volumio-home volumio[1057]: ---------------------------- MPD announces state update: player Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::getState Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand status Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand clearerror Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::parseState Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::sendMpdCommand playlistinfo Jan 20 08:57:25 volumio-home volumio[1057]: info: sending command... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: parsing response... Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::parseTrackInfo Jan 20 08:57:25 volumio-home volumio[1057]: info: ControllerMpd::pushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::servicePushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::syncState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::pushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreStateMachine::getState Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::volumioPushState Jan 20 08:57:25 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 08:57:25 volumio-home volumio[1057]: info: CorePlayQueue::getTrack 0 Jan 20 08:57:25 volumio-home volumio[1057]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Jan 20 08:57:25 volumio-home volumio[1057]: info: ------------------------------ 37ms Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_KEY" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "API_SECRET" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "username" is not set. Jan 20 08:57:25 volumio-home volumio[1057]: info: [LastFM] configuration error; "authToken" is not set. Jan 20 08:57:26 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:27 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:28 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:29 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:30 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 20 08:57:30 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:30 volumio-home volumio[1057]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jan 20 08:57:31 volumio-home volumio[1155]: Wrong scontrol identifier: Jan 20 08:57:31 volumio-home volumio[1057]: info: CoreCommandRouter::volumioAddQueueItems Jan 20 08:57:31 volumio-home volumio[1057]: info: CoreStateMachine::addQueueItems Jan 20 08:57:31 volumio-home volumio[1057]: info: CorePlayQueue::addQueueItems Jan 20 08:57:31 volumio-home volumio[1057]: info: Adding Item to queue: webkbs/0 Jan 20 08:57:31 volumio-home volumio[1057]: info: Exploding uri webkbs/0 in service personal_radio Jan 20 08:57:32 volumio-home volumio[1057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 08:57:32 volumio-home volumio[1057]: ReferenceError: self is not defined Jan 20 08:57:32 volumio-home volumio[1057]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jan 20 08:57:32 volumio-home volumio[1057]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jan 20 08:57:32 volumio-home volumio[1057]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Jan 20 08:57:32 volumio-home volumio[1057]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jan 20 08:57:32 volumio-home volumio[1057]: at emitOne (events.js:116:13) Jan 20 08:57:32 volumio-home volumio[1057]: at Request.emit (events.js:211:7) Jan 20 08:57:32 volumio-home volumio[1057]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Jan 20 08:57:32 volumio-home volumio[1057]: at emitOne (events.js:116:13) Jan 20 08:57:32 volumio-home volumio[1057]: at ClientRequest.emit (events.js:211:7) Jan 20 08:57:32 volumio-home volumio[1057]: at Socket.socketErrorListener (_http_client.js:387:9) Jan 20 08:57:32 volumio-home volumio[1057]: at emitOne (events.js:116:13) Jan 20 08:57:32 volumio-home volumio[1057]: at Socket.emit (events.js:211:7) Jan 20 08:57:32 volumio-home volumio[1057]: at emitErrorNT (internal/streams/destroy.js:64:8) Jan 20 08:57:32 volumio-home volumio[1057]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jan 20 08:57:32 volumio-home volumio[1057]: at process._tickCallback (internal/process/next_tick.js:180:9) Jan 20 08:57:32 volumio-home volumio[1057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 08:57:33 volumio-home sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 08:56 Jan 20 08:57:33 volumio-home sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="3686e5c749983c2b52f621497566574399fc1a6c" VOLUMIO_FE_VERSION="f027ce75738c64189018057558e04cba7a74a080" VOLUMIO_BE_VERSION="00a16140148367a337eb80a05ab0638fd10cd06e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2018年 08月 13日 星期一 07:01:39 CST" VOLUMIO_VERSION="2.444" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d41d8cd98f00b204e9800998ecf8427e"