-- Logs begin at Wed 2025-06-25 13:35:48 UTC, end at Wed 2025-09-10 06:50:50 UTC. -- Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::ClearQueue Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::stop Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 06:49:04 opera volumio[1392]: verbose: UNSET VOLATILE Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::stPlaybackTimer Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::updateTrackBlock Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrackBlock Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::pushState Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPushState Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::serviceStop Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::serviceStop Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::stop Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::clearPlayQueue Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::saveQueue Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPushState Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPushQueue Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::addQueueItems Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::addQueueItems Sep 10 06:49:04 opera volumio[1392]: info: Adding Item to queue: albums://Bob%20James/Cool Sep 10 06:49:04 opera volumio[1392]: info: Exploding uri albums://Bob%20James/Cool in service mpd Sep 10 06:49:04 opera volumio[1392]: info: Updating RAAT Signal Path Sep 10 06:49:04 opera volumio[1392]: info: Updating RAAT Signal Path Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::saveQueue Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPushQueue Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::updateTrackBlock Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrackBlock Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPlay Sep 10 06:49:04 opera volumio[1392]: verbose: UNSET VOLATILE Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::play index 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::stop Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 06:49:04 opera volumio[1392]: verbose: UNSET VOLATILE Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::play index undefined Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::startPlaybackTimer Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::clearAddPlayTracks USB/DSD..600/1..DSD/Cool/01 Bob James - Movin' On.flac Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand stop Sep 10 06:49:04 opera volumio[1392]: info: Sep 10 06:49:04 opera volumio[1392]: ---------------------------- MPD announces state update: player Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand stop took 86 milliseconds Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::getState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand status Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand stop took 50 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand clear Sep 10 06:49:04 opera volumio[1392]: info: Sep 10 06:49:04 opera volumio[1392]: ---------------------------- MPD announces system playlist update Sep 10 06:49:04 opera volumio[1392]: info: Ignoring MPD Status Update Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand status took 1 milliseconds Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand clear took 1 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand add "USB/DSD..600/1..DSD/Cool/01 Bob James - Movin' On.flac" Sep 10 06:49:04 opera volumio[1392]: error: updateQueue error: null Sep 10 06:49:04 opera volumio[1392]: info: Sep 10 06:49:04 opera volumio[1392]: ---------------------------- MPD announces system playlist update Sep 10 06:49:04 opera volumio[1392]: info: Ignoring MPD Status Update Sep 10 06:49:04 opera volumio[1392]: info: ------------------------------ 3ms Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand add "USB/DSD..600/1..DSD/Cool/01 Bob James - Movin' On.flac" took 2 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseTrackInfo Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand play Sep 10 06:49:04 opera volumio[1392]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 10 06:49:04 opera volumio[1392]: info: ------------------------------ 6ms Sep 10 06:49:04 opera volumio[1392]: info: ------------------------------ 3ms Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand play took 1 milliseconds Sep 10 06:49:04 opera volumio[1392]: info: Sep 10 06:49:04 opera volumio[1392]: ---------------------------- MPD announces state update: player Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::getState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand status Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand status took 2 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 06:49:04 opera volumio[1392]: info: Sep 10 06:49:04 opera volumio[1392]: ---------------------------- MPD announces state update: player Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::getState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand status Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseTrackInfo Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::pushState Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::servicePushState Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/DSD..600/1..DSD/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Sep 10 06:49:04 opera volumio[1392]: verbose: CURRENT POSITION 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::syncState stateService play Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::syncState currentStatus stop Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::pushState Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::volumioPushState Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:49:04 opera volumio[1392]: info: ------------------------------ 17ms Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand status took 14 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseState Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 10 06:49:04 opera volumio[1392]: info: Updating RAAT Signal Path Sep 10 06:49:04 opera volumio[1392]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 10 06:49:04 opera volumio[1392]: verbose: ControllerMpd::parseTrackInfo Sep 10 06:49:04 opera volumio[1392]: info: ControllerMpd::pushState Sep 10 06:49:04 opera volumio[1392]: info: CoreCommandRouter::servicePushState Sep 10 06:49:04 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:04 opera volumio[1392]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/DSD..600/1..DSD/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Sep 10 06:49:04 opera volumio[1392]: verbose: CURRENT POSITION 0 Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::syncState stateService play Sep 10 06:49:04 opera volumio[1392]: info: CoreStateMachine::syncState currentStatus play Sep 10 06:49:04 opera volumio[1392]: info: Received an update from plugin. extracting info from payload Sep 10 06:49:05 opera volumio[1392]: info: CoreStateMachine::pushState Sep 10 06:49:05 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:05 opera volumio[1392]: info: CoreCommandRouter::volumioPushState Sep 10 06:49:05 opera volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:49:05 opera volumio[1392]: info: CoreStateMachine::pushState Sep 10 06:49:05 opera volumio[1392]: info: CorePlayQueue::getTrack 0 Sep 10 06:49:05 opera volumio[1392]: info: CoreCommandRouter::volumioPushState Sep 10 06:49:05 opera volumio[1392]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:49:05 opera volumio[1392]: info: ------------------------------ 62ms Sep 10 06:49:05 opera volumio[1392]: info: Updating RAAT Signal Path Sep 10 06:49:05 opera volumio[1392]: info: Updating RAAT Signal Path Sep 10 06:49:31 opera dhcpd[1429]: DHCPDISCOVER from 48:e7:da:4e:fb:07 (opera) via wlan0 Sep 10 06:49:32 opera dhcpd[1429]: DHCPOFFER on 192.168.211.9 to 48:e7:da:4e:fb:07 (opera) via wlan0 Sep 10 06:50:28 opera volumio[1392]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/Red%20(Taylor%27s%20Version)/f464485a-d6bc-429c-8a55-68dcb63a8ab0.jpg' Sep 10 06:50:36 opera dhcpd[1429]: DHCPDISCOVER from 48:e7:da:4e:fb:07 (opera) via wlan0 Sep 10 06:50:36 opera volumio[1392]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 06:50:36 opera volumio[1392]: { Error: connect ETIMEDOUT 162.125.2.6:443 Sep 10 06:50:36 opera volumio[1392]: at Object._errnoException (util.js:1022:11) Sep 10 06:50:36 opera volumio[1392]: at _exceptionWithHostPort (util.js:1044:20) Sep 10 06:50:36 opera volumio[1392]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 10 06:50:36 opera volumio[1392]: code: 'ETIMEDOUT', Sep 10 06:50:36 opera volumio[1392]: errno: 'ETIMEDOUT', Sep 10 06:50:36 opera volumio[1392]: syscall: 'connect', Sep 10 06:50:36 opera volumio[1392]: address: '162.125.2.6', Sep 10 06:50:36 opera volumio[1392]: port: 443 } Sep 10 06:50:36 opera volumio[1392]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 06:50:37 opera dhcpd[1429]: DHCPOFFER on 192.168.211.9 to 48:e7:da:4e:fb:07 (opera) via wlan0 Sep 10 06:50:37 opera sudo[6109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 06:49 Sep 10 06:50:37 opera sudo[6109]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:37 opera sudo[6109]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:38 opera volumio-remote-updater[686]: [2025-09-10 06:50:38] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Sep 10 06:50:38 opera volumio-remote-updater[686]: [2025-09-10 06:50:38] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Sep 10 06:50:38 opera volumio-remote-updater[686]: [2025-09-10 06:50:38] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Sep 10 06:50:38 opera systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Sep 10 06:50:38 opera systemd[1]: Unit volumio.service entered failed state. Sep 10 06:50:38 opera systemd[1]: Starting dynamicswap service... Sep 10 06:50:38 opera systemd[1]: Started dynamicswap service. Sep 10 06:50:38 opera systemd[1]: volumio.service holdoff time over, scheduling restart. Sep 10 06:50:38 opera systemd[1]: Starting dynamicswap service... Sep 10 06:50:38 opera systemd[1]: Started dynamicswap service. Sep 10 06:50:38 opera systemd[1]: Stopping Volumio Backend Module... Sep 10 06:50:38 opera systemd[1]: Starting Volumio Backend Module... Sep 10 06:50:38 opera systemd[1]: Started Volumio Backend Module. Sep 10 06:50:40 opera volumio[6137]: info: ------------------------------------------- Sep 10 06:50:40 opera volumio[6137]: info: ----- Volumio2 ---- Sep 10 06:50:40 opera volumio[6137]: info: ------------------------------------------- Sep 10 06:50:40 opera volumio[6137]: info: ----- System startup ---- Sep 10 06:50:40 opera volumio[6137]: info: ------------------------------------------- Sep 10 06:50:40 opera volumio[6137]: info: Plugin folders cleanup Sep 10 06:50:40 opera volumio[6137]: info: Scanning into folder /volumio/app/plugins/ Sep 10 06:50:40 opera volumio[6137]: info: Scanning category audio_interface Sep 10 06:50:40 opera volumio[6137]: info: Scanning category miscellanea Sep 10 06:50:40 opera volumio[6137]: info: Scanning category music_service Sep 10 06:50:40 opera volumio[6137]: info: Scanning category plugins.json Sep 10 06:50:40 opera volumio[6137]: info: Scanning category system_controller Sep 10 06:50:40 opera volumio[6137]: info: Scanning category user_interface Sep 10 06:50:40 opera volumio[6137]: info: Scanning into folder /data/plugins/ Sep 10 06:50:40 opera volumio[6137]: info: Plugin folders cleanup completed Sep 10 06:50:40 opera volumio[6137]: info: ------------------------------------------- Sep 10 06:50:40 opera volumio[6137]: info: ----- Core plugins startup ---- Sep 10 06:50:40 opera volumio[6137]: info: ------------------------------------------- Sep 10 06:50:40 opera volumio[6137]: info: Loading plugins from folder /volumio/app/plugins/ Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin bluetooth to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin upnp to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin metavolumio to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin cd_controller to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin raat to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Adding plugin volspotconnect2 to MyMusic Plugins Sep 10 06:50:40 opera volumio[6137]: info: Loading plugins from folder /data/plugins/ Sep 10 06:50:40 opera volumio[6137]: info: Loading plugin "system"... Sep 10 06:50:40 opera volumio[6137]: info: Loading plugin "appearance"... Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "network"... Sep 10 06:50:41 opera volumio[6137]: info: Refreshing Cached IP Addresses Sep 10 06:50:41 opera sudo[6164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "services"... Sep 10 06:50:41 opera sudo[6164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "alsa_controller"... Sep 10 06:50:41 opera sudo[6168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 10 06:50:41 opera sudo[6164]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:41 opera sudo[6168]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:41 opera sudo[6168]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "wizard"... Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "volumio_command_line_client"... Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "upnp"... Sep 10 06:50:41 opera volumio[6137]: info: [1757487041691] Starting Upmpd Daemon Sep 10 06:50:41 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "my_music"... Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "mpd"... Sep 10 06:50:41 opera volumio[6137]: info: Loading plugin "upnp_browser"... Sep 10 06:50:42 opera volumio[6137]: info: Loading plugin "networkfs"... Sep 10 06:50:42 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 10 06:50:42 opera volumio[6137]: info: Loading plugin "bluetooth"... Sep 10 06:50:42 opera volumio[6137]: info: [1757487042477] Starting BluetoothController Sep 10 06:50:42 opera volumio[6137]: info: Loading plugin "alarm-clock"... Sep 10 06:50:42 opera volumio[6137]: info: Loading plugin "metavolumio"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "airplay_emulation"... Sep 10 06:50:43 opera volumio[6137]: info: Starting Shairport Sync Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "cd_controller"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "last_100"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "raat"... Sep 10 06:50:43 opera volumio-remote-updater[686]: [2025-09-10 06:50:43] [connect] Successful connection Sep 10 06:50:43 opera volumio[6137]: info: RAAT Plugin loaded Sep 10 06:50:43 opera volumio[6137]: info: Adding restartRAATSocket REST API Endpoint Sep 10 06:50:43 opera volumio[6137]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "streaming_services"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "webradio"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "i2s_dacs"... Sep 10 06:50:43 opera volumio[6137]: info: I2S DAC not set, start Auto-detection Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "volumiodiscovery"... Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 10 06:50:43 opera node[6137]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 10 06:50:43 opera node[6137]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 10 06:50:43 opera node[6137]: *** WARNING *** For more information see Sep 10 06:50:43 opera node[6137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 10 06:50:43 opera node[6137]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 10 06:50:43 opera node[6137]: *** WARNING *** For more information see Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** For more information see Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 10 06:50:43 opera volumio[6137]: *** WARNING *** For more information see Sep 10 06:50:43 opera volumio[6137]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 10 06:50:43 opera volumio[6137]: Discovery: StartAdv! undefined Sep 10 06:50:43 opera volumio[6137]: Discovery: Started advertising... Opera - undefined Sep 10 06:50:43 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "outputs"... Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "albumart"... Sep 10 06:50:43 opera volumio[6137]: info: Plugin example_plugin is not enabled Sep 10 06:50:43 opera volumio[6137]: info: Loading plugin "hi_res_audio"... Sep 10 06:50:44 opera volumio[6137]: Forking 3 albumart workers Sep 10 06:50:44 opera volumio[6137]: Starting albumart workers Sep 10 06:50:44 opera volumio[6137]: Starting albumart workers Sep 10 06:50:44 opera volumio[6137]: Starting albumart workers Sep 10 06:50:44 opera volumio[6137]: info: Applying required configuration parameters for plugin hi_res_audio Sep 10 06:50:44 opera volumio[6137]: info: Loading plugin "inputs"... Sep 10 06:50:44 opera volumio[6137]: info: Loading plugin "qobuz"... Sep 10 06:50:46 opera volumio[6137]: info: Loading plugin "tidal"... Sep 10 06:50:47 opera volumio[6137]: info: Loading plugin "volspotconnect2"... Sep 10 06:50:47 opera volumio[6137]: info: Loading plugin "updater_comm"... Sep 10 06:50:47 opera volumio[6137]: info: Plugin mpdemulation is not enabled Sep 10 06:50:47 opera volumio[6137]: info: Loading plugin "rest_api"... Sep 10 06:50:47 opera volumio[6137]: info: Loading plugin "websocket"... Sep 10 06:50:47 opera volumio[6137]: info: ___________ START PLUGINS ___________ Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 10 06:50:47 opera volumio[6137]: info: [1757487047599] CoreMusicLibrary::Adding element Media Servers Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 10 06:50:47 opera volumio[6137]: info: Adding METAVOLUMIO REST API Endpoints Sep 10 06:50:47 opera volumio[6137]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 10 06:50:47 opera sudo[6243]: pam_unix(sudo:auth): conversation failed Sep 10 06:50:47 opera sudo[6243]: pam_unix(sudo:auth): auth could not identify password for [volumio] Sep 10 06:50:47 opera volumio[6137]: info: Preparing CD Folders Sep 10 06:50:47 opera volumio[6137]: info: Adding CD REST API Endpoints Sep 10 06:50:47 opera volumio[6137]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Sep 10 06:50:47 opera volumio[6137]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Sep 10 06:50:47 opera volumio[6137]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Sep 10 06:50:47 opera volumio[6137]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 10 06:50:47 opera volumio[6137]: info: Detecting CD presence with Flag file Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 10 06:50:47 opera volumio[6137]: info: [1757487047722] CoreMusicLibrary::Adding element Last_100 Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 06:50:47 opera volumio[6137]: info: Starting RAAT Plugin Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 10 06:50:47 opera volumio[6137]: info: Additional UI Settings Added for plugin music_service/raat Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: Not Reporting Auto name since its the default one Sep 10 06:50:47 opera volumio[6137]: info: Streaming services startup Sep 10 06:50:47 opera volumio[6137]: info: Starting Streaming Daemon Sep 10 06:50:47 opera sudo[6258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 10 06:50:47 opera sudo[6258]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 10 06:50:47 opera volumio[6137]: info: [1757487047811] CoreMusicLibrary::Adding element Webradio Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 06:50:47 opera systemd[1]: Stopping Volumio Streaming Daemon... Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 10 06:50:47 opera systemd[1]: Starting Volumio Streaming Daemon... Sep 10 06:50:47 opera systemd[1]: Started Volumio Streaming Daemon. Sep 10 06:50:47 opera sudo[6258]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:47 opera volumio[6137]: error: Hi Res Audio Failed Login: Missing Login Data Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: ############################ Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: # Volumio Streaming Daemon # Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: # Running on port 7777 # Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: ############################ Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: Checking variant in static cache Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: Enabled services: Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: 1) tidal Sep 10 06:50:47 opera volumio-streaming-daemon[6269]: 2) qobuz Sep 10 06:50:47 opera volumio[6137]: info: Loading i18n strings for locale zh Sep 10 06:50:47 opera volumio[6137]: Updating browse sources language Sep 10 06:50:47 opera volumio[6137]: Cannot find translation for sourceMedia Servers Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 06:50:47 opera sudo[6279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 10 06:50:47 opera sudo[6279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::initPlayerControls Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera systemd[1]: Started Volspotconnect2 Daemon. Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 10 06:50:47 opera sudo[6279]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 10 06:50:47 opera volumio[6137]: info: BOOT COMPLETED Sep 10 06:50:47 opera volumio[6137]: [Metrics] CommandRouter: 7s 867.27ms Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 06:50:47 opera volumio[6137]: aplay: main:722: audio open error: Device or resource busy Sep 10 06:50:47 opera volumio[6137]: Cannot play startup sound Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::Close All Modals sent Sep 10 06:50:47 opera volumio[6137]: info: CoreCommandRouter::Close All Modals sent Sep 10 06:50:48 opera volumio[6137]: Express server listening on port 3000 Sep 10 06:50:48 opera volumio[6137]: [Metrics] WebUI: 8s 318.94ms Sep 10 06:50:48 opera volumio[6137]: Volumio Calling Home Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::resetVolumioState Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::getcurrentVolume Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioRetrievevolume Sep 10 06:50:48 opera volumio[6137]: info: Stopping AccessToken refresher cron for QOBUZ Sep 10 06:50:48 opera volumio[6137]: info: AccessToken refresher cron started for QOBUZ Sep 10 06:50:48 opera volumio[6137]: info: Stopping AccessToken refresher cron Sep 10 06:50:48 opera volumio[6137]: info: AccessToken refresher cron started Sep 10 06:50:48 opera volumio[6137]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 10 06:50:48 opera volumio[6137]: info: Setting Device type: Tinkerboard Sep 10 06:50:48 opera volumio[6137]: [SpotifyConnect] Vollibrespot Daemon Started Sep 10 06:50:48 opera volumio[6137]: [SpotifyConnect] Starting metadata listener Sep 10 06:50:48 opera volumio[6137]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Sep 10 06:50:48 opera volumio[6137]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera' Sep 10 06:50:48 opera volumio[6137]: We trust you have received the usual lecture from the local System Sep 10 06:50:48 opera volumio[6137]: Administrator. It usually boils down to these three things: Sep 10 06:50:48 opera volumio[6137]: #1) Respect the privacy of others. Sep 10 06:50:48 opera volumio[6137]: #2) Think before you type. Sep 10 06:50:48 opera volumio[6137]: #3) With great power comes great responsibility. Sep 10 06:50:48 opera volumio[6137]: sudo: no tty present and no askpass program specified Sep 10 06:50:48 opera volumio[6137]: info: MPD running with PID739 Sep 10 06:50:48 opera volumio[6137]: ,establishing connection Sep 10 06:50:48 opera sudo[6306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 10 06:50:48 opera sudo[6306]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:48 opera sudo[6306]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:48 opera volumio-remote-updater[686]: [2025-09-10 06:50:48] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1757487043 101 Sep 10 06:50:48 opera volumio[6137]: info: VolumeController:: Volume=100 Mute =false Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::pushState Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioPushState Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::updateTrackBlock Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrackBlock Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioRetrievevolume Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioGetState Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 10 06:50:48 opera volumio[6137]: info: Reloading queue from file Sep 10 06:50:48 opera sudo[6322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 10 06:50:48 opera sudo[6322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:48 opera systemd[1]: Stopping RAAT DAEMON... Sep 10 06:50:48 opera sudo[6322]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:48 opera systemd[1]: Starting RAAT DAEMON... Sep 10 06:50:48 opera systemd[1]: Started RAAT DAEMON. Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::setRepeat true single undefined Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::pushState Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioPushState Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::setRandom null Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::pushState Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioPushState Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:50:48 opera volumio[6137]: info: Raat Daemon started successfully Sep 10 06:50:48 opera volumio[6137]: info: Executing endpoint restartRAATSocket Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 10 06:50:48 opera volumio[6137]: info: VolumeController:: Volume=100 Mute =false Sep 10 06:50:48 opera volumio[6137]: info: CoreStateMachine::pushState Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::volumioPushState Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 10 06:50:48 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 10 06:50:48 opera volumio[6137]: info: Volumio called home Sep 10 06:50:48 opera volumio[6137]: info: Starting Shairport Sync Sep 10 06:50:48 opera volumio[6137]: info: Starting Shairport Sync Sep 10 06:50:48 opera sudo[6348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 10 06:50:48 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:48 opera sudo[6349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 10 06:50:48 opera sudo[6348]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:48 opera sudo[6349]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 10 06:50:48 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 10 06:50:48 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 10 06:50:48 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 10 06:50:48 opera sudo[6348]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:49 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 10 06:50:49 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 10 06:50:49 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 10 06:50:49 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 10 06:50:49 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 10 06:50:49 opera sudo[6349]: pam_unix(sudo:session): session closed for user root Sep 10 06:50:49 opera volumio[6137]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 10 06:50:49 opera volumio[6137]: Discovery: adding 6bcfc5d4-1cfe-45d5-a92a-f43d4363ccb9 Sep 10 06:50:49 opera volumio[6137]: info: mDNS: Found device Opera Sep 10 06:50:49 opera volumio[6137]: info: CoreCommandRouter::volumioGetState Sep 10 06:50:49 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:49 opera volumio[6137]: error: Could not retrieve plugin audio_interface multiroom Sep 10 06:50:49 opera volumio[6137]: error: Could not retrieve plugin audio_interface multiroom Sep 10 06:50:49 opera volumio[6137]: info: Shairport-Sync Started Sep 10 06:50:49 opera volumio[6137]: Error adding Membership: Error: addMembership EINVAL Sep 10 06:50:49 opera volumio[6137]: info: Shairport-Sync Started Sep 10 06:50:49 opera volumio[6137]: Discovery: this is already registered, 6bcfc5d4-1cfe-45d5-a92a-f43d4363ccb9 Sep 10 06:50:49 opera volumio[6137]: info: mDNS: Found device Opera Sep 10 06:50:49 opera volumio[6137]: info: CoreCommandRouter::volumioGetState Sep 10 06:50:49 opera volumio[6137]: info: CorePlayQueue::getTrack 0 Sep 10 06:50:49 opera volumio[6137]: error: Could not retrieve plugin audio_interface multiroom Sep 10 06:50:49 opera volumio[6137]: error: Could not retrieve plugin audio_interface multiroom Sep 10 06:50:49 opera volumio[6137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 06:50:49 opera volumio[6137]: { Error: connect ECONNREFUSED 4.78.139.50:443 Sep 10 06:50:49 opera volumio[6137]: at Object._errnoException (util.js:1022:11) Sep 10 06:50:49 opera volumio[6137]: at _exceptionWithHostPort (util.js:1044:20) Sep 10 06:50:49 opera volumio[6137]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Sep 10 06:50:49 opera volumio[6137]: code: 'ECONNREFUSED', Sep 10 06:50:49 opera volumio[6137]: errno: 'ECONNREFUSED', Sep 10 06:50:49 opera volumio[6137]: syscall: 'connect', Sep 10 06:50:49 opera volumio[6137]: address: '4.78.139.50', Sep 10 06:50:49 opera volumio[6137]: port: 443 } Sep 10 06:50:49 opera volumio[6137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 06:50:50 opera sudo[6393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 06:49 Sep 10 06:50:50 opera sudo[6393]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"