-- Logs begin at Mon 2025-05-26 12:55:29 +07, end at Tue 2025-05-27 01:30:04 +07. --
May 27 01:29:01 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 27 01:29:01 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 27 01:29:07 hifi-studio volumio[939]: info: Starting Uninstall of plugin system_hardware - audiophonicsonoff
May 27 01:29:07 hifi-studio volumio[939]: info: Uninstalling plugin audiophonicsonoff
May 27 01:29:07 hifi-studio volumio[939]: info: performing onStop action
May 27 01:29:07 hifi-studio volumio[939]: info: Disabling plugin audiophonicsonoff
May 27 01:29:07 hifi-studio volumio[939]: info: Checking if uninstall.sh is present
May 27 01:29:07 hifi-studio volumio[939]: info: Executing uninstall.sh
May 27 01:29:07 hifi-studio sudo[11194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/audiophonicsonoff/uninstall.sh
May 27 01:29:07 hifi-studio sudo[11194]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:07 hifi-studio sudo[11194]: pam_unix(sudo:session): session closed for user root
May 27 01:29:07 hifi-studio volumio[939]: info: Uninstall script completed
May 27 01:29:07 hifi-studio volumio[939]: info: Removing plugin audiophonicsonoff from configuration
May 27 01:29:07 hifi-studio volumio[939]: info: Successfully removed audiophonicsonoff configuration files
May 27 01:29:07 hifi-studio volumio[939]: info: Plugin folders cleanup
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning into folder /volumio/app/plugins/
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category audio_interface
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category miscellanea
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category music_service
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category plugins.json
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category system_controller
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category user_interface
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning into folder /data/plugins/
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category system_controller
May 27 01:29:07 hifi-studio volumio[939]: info: Scanning category system_hardware
May 27 01:29:07 hifi-studio volumio[939]: info: Cleaning folder for audiophonicsonoff
May 27 01:29:07 hifi-studio volumio[939]: info: Plugin folders cleanup completed
May 27 01:29:07 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 27 01:29:07 hifi-studio volumio[939]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true}]
May 27 01:29:07 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 27 01:29:08 hifi-studio volumio[939]: info: CoreCommandRouter::getUIConfigOnPlugin
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] Setting UI defaults
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] Port: 24
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] Inverted: false
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] Latched: false
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] On pulse width: 500
May 27 01:29:08 hifi-studio volumio[939]: info: [ASDebug] Off pulse width: 500
May 27 01:29:20 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 27 01:29:20 hifi-studio volumio[939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
May 27 01:29:22 hifi-studio volumio[939]: info: Starting Uninstall of plugin system_controller - ampswitch
May 27 01:29:22 hifi-studio volumio[939]: info: Uninstalling plugin ampswitch
May 27 01:29:22 hifi-studio volumio[939]: info: [ASDebug] Port: 24
May 27 01:29:22 hifi-studio volumio[939]: info: [ASDebug] Inverted: false
May 27 01:29:22 hifi-studio volumio[939]: info: [ASDebug] Delay: 720
May 27 01:29:24 hifi-studio volumiologrotate[566]: ls: cannot access '/var/log/samba/log.wb-HIFI': No such file or directory
May 27 01:29:24 hifi-studio volumiologrotate[566]: ls: cannot access 'STUDIO': No such file or directory
May 27 01:29:29 hifi-studio volumio[939]: info: Starting Uninstall of plugin system_controller - ampswitch
May 27 01:29:29 hifi-studio volumio[939]: info: Uninstalling plugin ampswitch
May 27 01:29:29 hifi-studio volumio[939]: info: [ASDebug] Port: 24
May 27 01:29:29 hifi-studio volumio[939]: info: [ASDebug] Inverted: false
May 27 01:29:29 hifi-studio volumio[939]: info: [ASDebug] Delay: 720
May 27 01:29:29 hifi-studio volumio[939]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 27 01:29:29 hifi-studio volumio[939]: Error: EBADF: bad file descriptor, close
May 27 01:29:29 hifi-studio volumio[939]: at Object.closeSync (fs.js:439:3)
May 27 01:29:29 hifi-studio volumio[939]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20)
May 27 01:29:29 hifi-studio volumio[939]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27)
May 27 01:29:29 hifi-studio volumio[939]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8)
May 27 01:29:29 hifi-studio volumio[939]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19)
May 27 01:29:29 hifi-studio volumio[939]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10)
May 27 01:29:29 hifi-studio volumio[939]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30)
May 27 01:29:29 hifi-studio volumio[939]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10)
May 27 01:29:29 hifi-studio volumio[939]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22)
May 27 01:29:29 hifi-studio volumio[939]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45)
May 27 01:29:29 hifi-studio volumio[939]: at Socket.emit (events.js:315:20)
May 27 01:29:29 hifi-studio volumio[939]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
May 27 01:29:29 hifi-studio volumio[939]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
May 27 01:29:29 hifi-studio volumio[939]: errno: -9,
May 27 01:29:29 hifi-studio volumio[939]: syscall: 'close',
May 27 01:29:29 hifi-studio volumio[939]: code: 'EBADF'
May 27 01:29:29 hifi-studio volumio[939]: }
May 27 01:29:29 hifi-studio volumio[939]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 27 01:29:30 hifi-studio sudo[11299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-27 01:28
May 27 01:29:30 hifi-studio sudo[11299]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:30 hifi-studio sudo[11299]: pam_unix(sudo:session): session closed for user root
May 27 01:29:31 hifi-studio volumio-remote-updater[554]: [2025-05-27 01:29:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
May 27 01:29:31 hifi-studio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
May 27 01:29:31 hifi-studio systemd[1]: volumio.service: Failed with result 'exit-code'.
May 27 01:29:31 hifi-studio volumio-remote-updater[554]: [2025-05-27 01:29:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
May 27 01:29:31 hifi-studio vtcs[15168]: [2025-05-27 01:29:31.352] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
May 27 01:29:31 hifi-studio qobuz-connect[15150]: 20250527 01:29:31.466 [15150.15150] INFO SampleApp: Socket closed
May 27 01:29:31 hifi-studio systemd[1]: Started dynamicswap service.
May 27 01:29:31 hifi-studio systemd[1]: upmpdcli.service: Succeeded.
May 27 01:29:31 hifi-studio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
May 27 01:29:31 hifi-studio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
May 27 01:29:31 hifi-studio systemd[1]: Stopped Volumio Backend Module.
May 27 01:29:31 hifi-studio systemd[1]: Started Volumio Backend Module.
May 27 01:29:31 hifi-studio systemd[1]: dynamicswap.service: Succeeded.
May 27 01:29:33 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:33 hifi-studio volumio[11307]: info: ----- Volumio3 ----
May 27 01:29:33 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:33 hifi-studio volumio[11307]: info: ----- System startup ----
May 27 01:29:33 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:33 hifi-studio volumio[11307]: info: MYVOLUMIO Environment detected
May 27 01:29:33 hifi-studio volumio[11307]: info: Plugin folders cleanup
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning into folder /volumio/app/plugins/
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category audio_interface
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category miscellanea
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category music_service
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category plugins.json
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category system_controller
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category user_interface
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning into folder /data/plugins/
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category system_controller
May 27 01:29:33 hifi-studio volumio[11307]: info: Scanning category system_hardware
May 27 01:29:33 hifi-studio volumio[11307]: info: Plugin folders cleanup completed
May 27 01:29:33 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:33 hifi-studio volumio[11307]: info: ----- Core plugins startup ----
May 27 01:29:33 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:33 hifi-studio volumio[11307]: info: Loading plugins from folder /volumio/app/plugins/
May 27 01:29:33 hifi-studio volumio[11307]: info: Adding plugin upnp to MyMusic Plugins
May 27 01:29:33 hifi-studio volumio[11307]: info: Adding plugin airplay_emulation to MyMusic Plugins
May 27 01:29:33 hifi-studio volumio[11307]: info: Adding plugin upnp_browser to MyMusic Plugins
May 27 01:29:33 hifi-studio volumio[11307]: info: Loading plugins from folder /data/plugins/
May 27 01:29:33 hifi-studio volumio[11307]: info: Loading plugin "system"...
May 27 01:29:33 hifi-studio volumio[11307]: info: Loading plugin "ampswitch"...
May 27 01:29:33 hifi-studio volumio[11307]: info: Applying required configuration parameters for plugin ampswitch
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "appearance"...
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "network"...
May 27 01:29:34 hifi-studio volumio[11307]: info: Refreshing Cached IP Addresses
May 27 01:29:34 hifi-studio sudo[11334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 27 01:29:34 hifi-studio sudo[11334]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:34 hifi-studio sudo[11334]: pam_unix(sudo:session): session closed for user root
May 27 01:29:34 hifi-studio sudo[11336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 27 01:29:34 hifi-studio sudo[11336]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:34 hifi-studio sudo[11336]: pam_unix(sudo:session): session closed for user root
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "services"...
May 27 01:29:34 hifi-studio sudo[11349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "alsa_controller"...
May 27 01:29:34 hifi-studio sudo[11349]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:34 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "wizard"...
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "networkfs"...
May 27 01:29:34 hifi-studio volumio[11307]: info: Starting Udev Watcher for removable devices
May 27 01:29:34 hifi-studio volumio[11307]: info: Ignoring mount for partition: boot
May 27 01:29:34 hifi-studio volumio[11307]: info: Ignoring mount for partition: volumio
May 27 01:29:34 hifi-studio volumio[11307]: info: Ignoring mount for partition: volumio_data
May 27 01:29:34 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "volumio_command_line_client"...
May 27 01:29:34 hifi-studio volumio[11307]: info: Loading plugin "upnp"...
May 27 01:29:34 hifi-studio volumio[11307]: info: [1748284174998] Starting Upmpd Daemon
May 27 01:29:34 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "my_music"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "mpd"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "upnp_browser"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "alarm-clock"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "airplay_emulation"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Starting Shairport Sync
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "last_100"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "webradio"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "i2s_dacs"...
May 27 01:29:35 hifi-studio volumio[11307]: info: I2S DAC not set, start Auto-detection
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "volumiodiscovery"...
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** For more information see
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 27 01:29:35 hifi-studio volumio[11307]: *** WARNING *** For more information see
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** For more information see
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 27 01:29:35 hifi-studio node[11307]: *** WARNING *** For more information see
May 27 01:29:35 hifi-studio volumio[11307]: info: Applying required configuration parameters for plugin volumiodiscovery
May 27 01:29:35 hifi-studio volumio[11307]: info: Discovery: Started advertising with name: HiFi Studio
May 27 01:29:35 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "outputs"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "albumart"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Plugin example_plugin is not enabled
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "inputs"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "updater_comm"...
May 27 01:29:35 hifi-studio volumio[11307]: info: Plugin mpdemulation is not enabled
May 27 01:29:35 hifi-studio volumio[11307]: info: Loading plugin "rest_api"...
May 27 01:29:36 hifi-studio volumio[11307]: info: Loading plugin "websocket"...
May 27 01:29:36 hifi-studio volumio[11307]: info: Starting Socket.io Server version 2.3.0
May 27 01:29:36 hifi-studio volumio[11307]: info: Loading i18n strings for locale en
May 27 01:29:36 hifi-studio volumio[11307]: Updating browse sources language
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:36 hifi-studio volumio[11307]: Forking 3 albumart workers
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::initPlayerControls
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: Express server listening on port 3000
May 27 01:29:36 hifi-studio volumio[11307]: [Metrics] WebUI: 3s 749.34ms
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::resetVolumioState
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::getcurrentVolume
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioRetrievevolume
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:29:36 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: Volumio Network Manager: Network status updated: 2
May 27 01:29:36 hifi-studio volumio-remote-updater[554]: [2025-05-27 01:29:36] [connect] Successful connection
May 27 01:29:36 hifi-studio volumio[11307]: verbose: New Socket.io Connection to 192.168.2.35:3000 from 192.168.2.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
May 27 01:29:36 hifi-studio volumio[11307]: verbose: New Socket.io Connection to 192.168.2.35 from 192.168.31.100 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.5735.289 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
May 27 01:29:36 hifi-studio volumio[11307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
May 27 01:29:36 hifi-studio volumio[11307]: info: Reloading queue from file
May 27 01:29:36 hifi-studio volumio-remote-updater[554]: [2025-05-27 01:29:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748284176 101
May 27 01:29:36 hifi-studio volumio[11307]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::setRepeat null single undefined
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:29:36 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::setRandom null
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:29:36 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:29:36 hifi-studio volumio[11307]: info: Setting Device type: Raspberry PI
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
May 27 01:29:36 hifi-studio volumio[11307]: info: Completed loading Core Plugins
May 27 01:29:36 hifi-studio volumio[11307]: info: Preparing to generate the ALSA configuration file
May 27 01:29:36 hifi-studio volumio[11307]: info: Asound.conf file written
May 27 01:29:36 hifi-studio volumio[11307]: Starting albumart workers
May 27 01:29:36 hifi-studio volumio[11307]: Starting albumart workers
May 27 01:29:36 hifi-studio sudo[11398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
May 27 01:29:36 hifi-studio sudo[11398]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:36 hifi-studio sudo[11398]: pam_unix(sudo:session): session closed for user root
May 27 01:29:36 hifi-studio volumio[11307]: info: Output device has changed, restarting MPD
May 27 01:29:36 hifi-studio volumio[11307]: Starting albumart workers
May 27 01:29:36 hifi-studio sudo[11403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 27 01:29:36 hifi-studio sudo[11403]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:36 hifi-studio volumio[11307]: info: Output device has changed, restarting Shairport Sync
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:36 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:36 hifi-studio sudo[11403]: pam_unix(sudo:session): session closed for user root
May 27 01:29:36 hifi-studio sudo[11406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 27 01:29:36 hifi-studio sudo[11406]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:36 hifi-studio volumio[11307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 27 01:29:36 hifi-studio volumio[11307]: info: ___________ START PLUGINS ___________
May 27 01:29:36 hifi-studio systemd[1]: Stopping Music Player Daemon...
May 27 01:29:36 hifi-studio volumio[11307]: info: ControllerMpd::onStart: Initializing MPD
May 27 01:29:36 hifi-studio volumio[11307]: info: Creating MPD Configuration file
May 27 01:29:37 hifi-studio sudo[11424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 27 01:29:37 hifi-studio sudo[11424]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:37 hifi-studio sudo[11426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 27 01:29:37 hifi-studio sudo[11426]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 27 01:29:37 hifi-studio volumio[11307]: info: [1748284177015] CoreMusicLibrary::Adding element Media Servers
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:37 hifi-studio sudo[11424]: pam_unix(sudo:session): session closed for user root
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 27 01:29:37 hifi-studio volumio[11307]: info: [1748284177167] CoreMusicLibrary::Adding element Last_100
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 27 01:29:37 hifi-studio volumio[11307]: info: [1748284177170] CoreMusicLibrary::Adding element Webradio
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 27 01:29:37 hifi-studio volumio[11307]: info: Initializing BBC Radios
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: Volumio Calling Home
May 27 01:29:37 hifi-studio volumio[11307]: info: MPD Permissions set
May 27 01:29:37 hifi-studio volumio[11307]: info: MPD Permissions set
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:37 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:37 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:37 hifi-studio volumio[11307]: info: [ASDebug] CurState: stop PrevState: na
May 27 01:29:37 hifi-studio volumio[11307]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
May 27 01:29:37 hifi-studio volumio[11307]: info: [ASDebug] CurState: stop PrevState: na
May 27 01:29:37 hifi-studio volumio[11307]: info: [ASDebug] InitTimeout - Amp off in: 720 ms
May 27 01:29:37 hifi-studio volumio[11307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:37 hifi-studio volumio[11307]: info: Starting Shairport Sync
May 27 01:29:37 hifi-studio volumio[11307]: info: Starting Shairport Sync
May 27 01:29:37 hifi-studio volumio[11307]: info: Starting Shairport Sync
May 27 01:29:37 hifi-studio sudo[11486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 27 01:29:37 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:37 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:37 hifi-studio sudo[11486]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:37 hifi-studio sudo[11484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 27 01:29:37 hifi-studio sudo[11484]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:37 hifi-studio sudo[11482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 27 01:29:37 hifi-studio sudo[11482]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:37 hifi-studio volumio[11307]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
May 27 01:29:37 hifi-studio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 27 01:29:37 hifi-studio sudo[11484]: pam_unix(sudo:session): session closed for user root
May 27 01:29:37 hifi-studio sudo[11482]: pam_unix(sudo:session): session closed for user root
May 27 01:29:37 hifi-studio volumio[11307]: info: Shairport-Sync Started
May 27 01:29:37 hifi-studio sudo[11486]: pam_unix(sudo:session): session closed for user root
May 27 01:29:37 hifi-studio volumio[11307]: Error adding Membership: Error: addMembership EINVAL
May 27 01:29:37 hifi-studio volumio[11307]: info: Shairport-Sync Started
May 27 01:29:37 hifi-studio volumio[11307]: info: Shairport-Sync Started
May 27 01:29:38 hifi-studio volumio[11307]: info: Volumio called home
May 27 01:29:38 hifi-studio volumio[11307]: info: [ASDebug] Togle GPIO: OFF
May 27 01:29:38 hifi-studio systemd[1]: mpd.service: Succeeded.
May 27 01:29:38 hifi-studio systemd[1]: Stopped Music Player Daemon.
May 27 01:29:38 hifi-studio systemd[1]: Starting Music Player Daemon...
May 27 01:29:38 hifi-studio sudo[11493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 27 01:29:38 hifi-studio sudo[11493]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:38 hifi-studio sudo[11493]: pam_unix(sudo:session): session closed for user root
May 27 01:29:38 hifi-studio sudo[11349]: pam_unix(sudo:session): session closed for user root
May 27 01:29:39 hifi-studio mpd[11496]: May 27 01:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 27 01:29:39 hifi-studio systemd[1]: Started Music Player Daemon.
May 27 01:29:39 hifi-studio sudo[11426]: pam_unix(sudo:session): session closed for user root
May 27 01:29:39 hifi-studio sudo[11406]: pam_unix(sudo:session): session closed for user root
May 27 01:29:39 hifi-studio volumio[11307]: info: Completed starting Core Plugins
May 27 01:29:39 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:39 hifi-studio volumio[11307]: info: ----- MyVolumio plugins startup ----
May 27 01:29:39 hifi-studio volumio[11307]: info: -------------------------------------------
May 27 01:29:39 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Fetching plans data....
May 27 01:29:39 hifi-studio volumio[11307]: error: MPD error: The expression evaluated to a falsy value:
May 27 01:29:39 hifi-studio volumio[11307]: assert.ok(self.idling)
May 27 01:29:39 hifi-studio volumio[11307]: error: The expression evaluated to a falsy value:
May 27 01:29:39 hifi-studio volumio[11307]: assert.ok(self.idling)
May 27 01:29:39 hifi-studio volumio[11307]: info: MPD running with PID11496
May 27 01:29:39 hifi-studio volumio[11307]: ,establishing connection
May 27 01:29:39 hifi-studio volumio[11307]: error: MPD error: The expression evaluated to a falsy value:
May 27 01:29:39 hifi-studio volumio[11307]: assert.ok(self.idling)
May 27 01:29:39 hifi-studio volumio[11307]: error: The expression evaluated to a falsy value:
May 27 01:29:39 hifi-studio volumio[11307]: assert.ok(self.idling)
May 27 01:29:39 hifi-studio volumio[11307]: error: updateQueue error: null
May 27 01:29:39 hifi-studio volumio[11307]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007
May 27 01:29:40 hifi-studio volumio[11307]: verbose: New Socket.io Connection to 192.168.2.35 from 192.168.31.100 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.5735.289 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetVisibleSources
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:40 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:40 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 27 01:29:40 hifi-studio volumio[11307]: info: Received Get System Info
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 27 01:29:40 hifi-studio volumio[11307]: info: Discovery: Getting this device information
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:40 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:40 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:40 hifi-studio volumio[11307]: info: Listing playlists
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetQueue
May 27 01:29:40 hifi-studio volumio[11307]: info: CoreStateMachine::getQueue
May 27 01:29:40 hifi-studio volumio[11307]: info: CorePlayQueue::getQueue
May 27 01:29:44 hifi-studio volumio[11307]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
May 27 01:29:45 hifi-studio sudo[11521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 27 01:29:45 hifi-studio sudo[11521]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:45 hifi-studio sudo[11521]: pam_unix(sudo:session): session closed for user root
May 27 01:29:45 hifi-studio sudo[11523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 27 01:29:45 hifi-studio sudo[11523]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:45 hifi-studio sudo[11523]: pam_unix(sudo:session): session closed for user root
May 27 01:29:45 hifi-studio sudo[11527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 27 01:29:45 hifi-studio sudo[11527]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:45 hifi-studio systemd[1]: Started UPnP Renderer front-end to MPD.
May 27 01:29:45 hifi-studio sudo[11527]: pam_unix(sudo:session): session closed for user root
May 27 01:29:45 hifi-studio volumio[11307]: info: Upmpdcli Daemon Started
May 27 01:29:45 hifi-studio volumio[11529]: writing RSA key
May 27 01:29:46 hifi-studio volumio[11307]: info: [ASDebug] Port: 24
May 27 01:29:46 hifi-studio volumio[11307]: info: [ASDebug] Inverted: false
May 27 01:29:46 hifi-studio volumio[11307]: info: [ASDebug] Delay: 720
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin bluetooth to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin multiroom to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin metavolumio to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin cd_controller to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin qobuzconnect to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin smart_inputs to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: Adding plugin tidalconnect to MyMusic Plugins
May 27 01:29:48 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 27 01:29:49 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:49 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:49 hifi-studio volumio[11307]: info: Starting MyVolumio Remote Streaming Endpoints
May 27 01:29:49 hifi-studio volumio[11307]: info: MyVolumio login type: Token
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 27 01:29:49 hifi-studio volumio[11307]: info: Starting Streaming Service Transparent Proxy
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 27 01:29:49 hifi-studio volumio[11307]: info: Streaming services startup
May 27 01:29:49 hifi-studio volumio[11307]: info: Starting Streaming Daemon
May 27 01:29:49 hifi-studio sudo[11567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 27 01:29:49 hifi-studio sudo[11567]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:49 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 27 01:29:49 hifi-studio sudo[11567]: pam_unix(sudo:session): session closed for user root
May 27 01:29:49 hifi-studio volumio[11307]: info: Starting Uninstall of plugin system_controller - ampswitch
May 27 01:29:49 hifi-studio volumio[11307]: info: Uninstalling plugin ampswitch
May 27 01:29:49 hifi-studio volumio[11307]: info: [ASDebug] Port: 24
May 27 01:29:49 hifi-studio volumio[11307]: info: [ASDebug] Inverted: false
May 27 01:29:49 hifi-studio volumio[11307]: info: [ASDebug] Delay: 720
May 27 01:29:49 hifi-studio volumio[11307]: error: Cannot start Volumio Streaming Daemon
May 27 01:29:49 hifi-studio volumio[11307]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 27 01:29:49 hifi-studio volumio[11307]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 27 01:29:50 hifi-studio volumio[11307]: info: ERROR LOADING JSON Error: EBADF: bad file descriptor, fstat
May 27 01:29:50 hifi-studio volumio[11307]: error: MyVolumio Custom Token format not valid, refreshing it
May 27 01:29:50 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:50 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:50 hifi-studio volumio[11307]: STREAMING PROXY: Starting server on port 3245
May 27 01:29:50 hifi-studio volumio[11307]: Node JS runtime: 14
May 27 01:29:51 hifi-studio volumio[11307]: info: MyVolumio login type: Token
May 27 01:29:51 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPlay
May 27 01:29:51 hifi-studio volumio[11307]: info: CoreStateMachine::play index undefined
May 27 01:29:51 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService undefined
May 27 01:29:51 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:51 hifi-studio volumio[11307]: info: CoreStateMachine::startPlaybackTimer
May 27 01:29:51 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:52 hifi-studio volumio[11307]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 27 01:29:53 hifi-studio volumio[11307]: info: MyVolumio token set successfully
May 27 01:29:53 hifi-studio volumio[11307]: info: MYVOLUMIO: Adding device
May 27 01:29:53 hifi-studio volumio[11307]: info: MYVOLUMIO: Evaluating Server
May 27 01:29:53 hifi-studio volumio[11307]: info: MyVolumio Plan changed: premium
May 27 01:29:53 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
May 27 01:29:53 hifi-studio volumio[11307]: info: Removing browser output: myVolumio user plan is not superstar
May 27 01:29:53 hifi-studio volumio[11307]: info: Removing audio output:
May 27 01:29:53 hifi-studio volumio[11307]: info: MYVOLUMIO: Adding device
May 27 01:29:53 hifi-studio volumio[11307]: info: MYVOLUMIO: Evaluating Server
May 27 01:29:53 hifi-studio volumio[11307]: info: Remote config written successfully
May 27 01:29:53 hifi-studio volumio[11307]: info: Starting Tunnel 1
May 27 01:29:53 hifi-studio volumio[11307]: info: Starting Tunnel Connection Checker
May 27 01:29:53 hifi-studio volumio[11307]: info: Completed starting MyVolumio Plugin
May 27 01:29:53 hifi-studio volumio[11307]: info: MYVolumio Device enabled
May 27 01:29:53 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
May 27 01:29:53 hifi-studio volumio[11307]: info: MyVolumio status changed
May 27 01:29:53 hifi-studio volumio[11307]: info: Streaming services startup
May 27 01:29:53 hifi-studio volumio[11307]: info: Starting Streaming Daemon
May 27 01:29:53 hifi-studio volumio[11307]: info: Setting Geolocation for MyVolumio to as1
May 27 01:29:53 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:53 hifi-studio sudo[11607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 27 01:29:53 hifi-studio sudo[11607]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:53 hifi-studio sudo[11607]: pam_unix(sudo:session): session closed for user root
May 27 01:29:53 hifi-studio volumio[11307]: error: Cannot start Volumio Streaming Daemon
May 27 01:29:53 hifi-studio volumio[11307]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 27 01:29:53 hifi-studio volumio[11307]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 27 01:29:53 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
May 27 01:29:53 hifi-studio volumio[11307]: error: [MyVolumio PluginManager] Cache data is invalid!
May 27 01:29:54 hifi-studio volumio[11307]: info: Setting Geolocation for MyVolumio to as1
May 27 01:29:54 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:54 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:54 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
May 27 01:29:54 hifi-studio volumio[11307]: info: [1748284194677] Starting BluetoothController
May 27 01:29:54 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
May 27 01:29:55 hifi-studio sudo[11624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
May 27 01:29:55 hifi-studio sudo[11624]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:55 hifi-studio sudo[11624]: pam_unix(sudo:session): session closed for user root
May 27 01:29:55 hifi-studio volumio[11307]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
May 27 01:29:55 hifi-studio volumio[11307]: info: MRS: MultiRoom plugin initialized
May 27 01:29:55 hifi-studio volumio[11307]: info: MRS: STOPPING SNAPCLIENT
May 27 01:29:55 hifi-studio volumio[11307]: info: MRS: Snap server stop
May 27 01:29:55 hifi-studio sudo[11640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
May 27 01:29:55 hifi-studio sudo[11640]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:55 hifi-studio volumio[11307]: info: MRS: STOPPING volumioStreaming
May 27 01:29:55 hifi-studio sudo[11643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
May 27 01:29:55 hifi-studio sudo[11643]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:55 hifi-studio sudo[11640]: pam_unix(sudo:session): session closed for user root
May 27 01:29:55 hifi-studio sudo[11645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
May 27 01:29:55 hifi-studio sudo[11645]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
May 27 01:29:55 hifi-studio sudo[11649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
May 27 01:29:55 hifi-studio sudo[11649]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:55 hifi-studio sudo[11649]: pam_unix(sudo:session): session closed for user root
May 27 01:29:55 hifi-studio sudo[11643]: pam_unix(sudo:session): session closed for user root
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
May 27 01:29:55 hifi-studio sudo[11645]: pam_unix(sudo:session): session closed for user root
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
May 27 01:29:55 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
May 27 01:29:56 hifi-studio volumio[11307]: info: Preparing to generate the ALSA configuration file
May 27 01:29:56 hifi-studio volumio[11307]: info: Updating MyVolumio device info
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: Updating MyVolumio device info
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
May 27 01:29:56 hifi-studio volumio[11307]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
May 27 01:29:56 hifi-studio volumio[11307]: info: Reading ALSA contributions from plugins.
May 27 01:29:56 hifi-studio volumio[11307]: info: Successfully Added MyVolumio device
May 27 01:29:56 hifi-studio volumio[11307]: info: Successfully Added MyVolumio device
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: Removed streaming files
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: volumioStreaming STOPPED
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: SNAPSERVER STOPPED
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: SNAPCLIENT STOPPED
May 27 01:29:56 hifi-studio volumio[11307]: info: Asound.conf file written
May 27 01:29:56 hifi-studio sudo[11652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
May 27 01:29:56 hifi-studio sudo[11652]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio sudo[11652]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio volumio[11307]: info: Output device has changed, restarting MPD
May 27 01:29:56 hifi-studio sudo[11657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 27 01:29:56 hifi-studio sudo[11657]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio volumio[11307]: info: Output device has changed, restarting Shairport Sync
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio sudo[11657]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio sudo[11660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 27 01:29:56 hifi-studio sudo[11660]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio systemd[1]: Stopping Music Player Daemon...
May 27 01:29:56 hifi-studio volumio[11307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio systemd[1]: mpd.service: Succeeded.
May 27 01:29:56 hifi-studio systemd[1]: Stopped Music Player Daemon.
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
May 27 01:29:56 hifi-studio sudo[11669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name HiFi Studio
May 27 01:29:56 hifi-studio sudo[11669]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio volumio[11307]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding METAVOLUMIO REST API Endpoints
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
May 27 01:29:56 hifi-studio volumio[11307]: info: Preparing CD Folders
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding CD REST API Endpoints
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
May 27 01:29:56 hifi-studio volumio[11307]: info: Starting UDEV Watcher for CD
May 27 01:29:56 hifi-studio volumio[11307]: info: Detecting CD presence with UDEV
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding inputs REST Endpoints
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
May 27 01:29:56 hifi-studio volumio[11307]: info: Scanning Audio Inputs
May 27 01:29:56 hifi-studio systemd[1]: Starting Music Player Daemon...
May 27 01:29:56 hifi-studio sudo[11669]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio volumio[11307]: info: Checking against Known Cards name
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding Server instance for streaming
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
May 27 01:29:56 hifi-studio volumio[11307]: error: Hi Res Audio Failed Login: Missing Login Data
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding HIGHRESAUDIO REST API Endpoints
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
May 27 01:29:56 hifi-studio volumio[11307]: info: Refreshing TIDAL token
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
May 27 01:29:56 hifi-studio volumio[11307]: info: Refreshing QOBUZ token
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
May 27 01:29:56 hifi-studio sudo[11666]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 27 01:29:56 hifi-studio sudo[11666]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio sudo[11666]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio volumio[11307]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
May 27 01:29:56 hifi-studio sudo[11680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
May 27 01:29:56 hifi-studio sudo[11680]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio volumio[11307]: info: QobuzConnect: Starting Qobuz Connect socket and service
May 27 01:29:56 hifi-studio volumio[11307]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
May 27 01:29:56 hifi-studio sudo[11686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
May 27 01:29:56 hifi-studio sudo[11686]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio volumio[11307]: info: Bluetooth name changed to HiFi Studio
May 27 01:29:56 hifi-studio volumio[11307]: info: MPD Permissions set
May 27 01:29:56 hifi-studio systemd[1]: Stopping Volumio Tidal Connect Service...
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
May 27 01:29:56 hifi-studio sudo[11686]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio systemd[1]: vtcs.service: Succeeded.
May 27 01:29:56 hifi-studio systemd[1]: Stopped Volumio Tidal Connect Service.
May 27 01:29:56 hifi-studio sudo[11680]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio sudo[11688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
May 27 01:29:56 hifi-studio sudo[11688]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:29:56 hifi-studio volumio[11307]: info: Starting Shairport Sync
May 27 01:29:56 hifi-studio systemd[1]: Stopping Volumio Qobuz Connect Service...
May 27 01:29:56 hifi-studio qobuz-connect[15150]: 20250527 01:29:56.564 [15150.15150] INFO SampleApp: Stopping Local configuration server
May 27 01:29:56 hifi-studio sudo[11696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 27 01:29:56 hifi-studio sudo[11696]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:56 hifi-studio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 27 01:29:56 hifi-studio systemd[1]: shairport-sync.service: Succeeded.
May 27 01:29:56 hifi-studio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 27 01:29:56 hifi-studio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 27 01:29:56 hifi-studio sudo[11696]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio volumio[11307]: info: Shairport-Sync Started
May 27 01:29:56 hifi-studio systemd[1]: qobuz-connect.service: Succeeded.
May 27 01:29:56 hifi-studio systemd[1]: Stopped Volumio Qobuz Connect Service.
May 27 01:29:56 hifi-studio systemd[1]: Started Volumio Qobuz Connect Service.
May 27 01:29:56 hifi-studio sudo[11688]: pam_unix(sudo:session): session closed for user root
May 27 01:29:56 hifi-studio volumio[11307]: info: Executing endpoint qc_getconfig
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.699 [11699.11699] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
May 27 01:29:56 hifi-studio volumio[11307]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
May 27 01:29:56 hifi-studio volumio[11307]: info: QobuzConnect: QOBUZ Connect daemon connected
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.712 [11699.11699] INFO VolumeManager: [0xe3d328]: Setting new playback volume: 75
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.713 [11699.11699] INFO VolumeManager: [0xe3d328]: Setting new mute state: 0
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.713 [11699.11699] INFO QobuzConnect: [0xe3dcf8]: Client initialized!
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.713 [11699.11699] INFO SampleApp: Starting Avahi advertising, name: HiFi Studio, service name: _qobuz-connect._tcp
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.720 [11699.11699] INFO LocalConfigManager: [0xe3cc08]: Starting Local Configuration server
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.721 [11699.11699] INFO SampleApp: Starting Local configuration server
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.721 [11699.11699] INFO SampleApp: Connected to UNIX socket client 0xe32f60
May 27 01:29:56 hifi-studio qobuz-connect[11699]: 20250527 01:29:56.848 [11699.11699] INFO SampleApp: Playback volume changed: 75
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:56 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:56 hifi-studio volumio[11307]: info: Access Token successfully retrieved
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 27 01:29:56 hifi-studio volumio[11307]: info: [1748284196861] CoreMusicLibrary::Adding element QOBUZ
May 27 01:29:56 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:56 hifi-studio volumio[11307]: Cannot find translation for source QOBUZ
May 27 01:29:56 hifi-studio volumio[11307]: info: Stopping AccessToken refresher cron for QOBUZ
May 27 01:29:56 hifi-studio volumio[11307]: info: AccessToken refresher cron started for QOBUZ
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding QOBUZ REST API Endpoints
May 27 01:29:56 hifi-studio volumio[11307]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
May 27 01:29:56 hifi-studio volumio[11307]: info: Successfully Updated MyVolumio device
May 27 01:29:56 hifi-studio volumio[11307]: info: Successfully Updated MyVolumio device
May 27 01:29:57 hifi-studio mpd[11679]: May 27 01:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 27 01:29:57 hifi-studio systemd[1]: Started Music Player Daemon.
May 27 01:29:57 hifi-studio sudo[11660]: pam_unix(sudo:session): session closed for user root
May 27 01:29:57 hifi-studio volumio[11307]: error: updateQueue error: null
May 27 01:29:57 hifi-studio volumio[11307]: info: Access Token successfully retrieved
May 27 01:29:58 hifi-studio volumio[11307]: info: Successfully retrieved User Session From TIDAL
May 27 01:29:58 hifi-studio volumio[11307]: info: Successfully retrieved User Subscription From TIDAL
May 27 01:29:58 hifi-studio volumio[11307]: info: Adding TIDAL to Browse Sources
May 27 01:29:58 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 27 01:29:58 hifi-studio volumio[11307]: info: [1748284198535] CoreMusicLibrary::Adding element TIDAL
May 27 01:29:58 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 27 01:29:58 hifi-studio volumio[11307]: Cannot find translation for source QOBUZ
May 27 01:29:58 hifi-studio volumio[11307]: Cannot find translation for source TIDAL
May 27 01:29:58 hifi-studio sudo[11711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
May 27 01:29:58 hifi-studio sudo[11711]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:58 hifi-studio volumio[11307]: info: Stopping AccessToken refresher cron
May 27 01:29:58 hifi-studio volumio[11307]: info: AccessToken refresher cron started
May 27 01:29:58 hifi-studio volumio[11307]: info: Adding TIDAL REST API Endpoints
May 27 01:29:58 hifi-studio volumio[11307]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
May 27 01:29:58 hifi-studio systemd[1]: Stopping MyVolumio SSH Tunnel...
May 27 01:29:58 hifi-studio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM
May 27 01:29:58 hifi-studio autossh[1366]: received signal to exit (15)
May 27 01:29:58 hifi-studio systemd[1]: sshtunnel.service: Succeeded.
May 27 01:29:58 hifi-studio systemd[1]: Stopped MyVolumio SSH Tunnel.
May 27 01:29:58 hifi-studio systemd[1]: Started MyVolumio SSH Tunnel.
May 27 01:29:58 hifi-studio sudo[11711]: pam_unix(sudo:session): session closed for user root
May 27 01:29:58 hifi-studio volumio[11307]: info: Remote SSH Started
May 27 01:29:58 hifi-studio autossh[11714]: port set to 0, monitoring disabled
May 27 01:29:58 hifi-studio autossh[11714]: starting ssh (count 1)
May 27 01:29:58 hifi-studio autossh[11714]: ssh child pid is 11717
May 27 01:29:58 hifi-studio volumio[11307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
May 27 01:29:58 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:58 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:58 hifi-studio volumiossh-tunnel[11713]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts.
May 27 01:29:59 hifi-studio sudo[11719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
May 27 01:29:59 hifi-studio sudo[11719]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:59 hifi-studio sudo[11719]: pam_unix(sudo:session): session closed for user root
May 27 01:29:59 hifi-studio volumio[11307]: info: Volumio BT Module successfully started
May 27 01:29:59 hifi-studio volumio[11307]: info: TidalConnect service stoped!
May 27 01:29:59 hifi-studio volumio[11307]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
May 27 01:29:59 hifi-studio volumio[11307]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
May 27 01:29:59 hifi-studio sudo[11730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
May 27 01:29:59 hifi-studio sudo[11730]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 27 01:29:59 hifi-studio systemd[1]: Started Volumio Tidal Connect Service.
May 27 01:29:59 hifi-studio sudo[11730]: pam_unix(sudo:session): session closed for user root
May 27 01:29:59 hifi-studio volumio[11307]: info: Executing endpoint tc_getconfig
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
May 27 01:29:59 hifi-studio vtcs[11732]: STARTING TidalConnect services, version: 1.4.0.34
May 27 01:29:59 hifi-studio vtcs[11732]: STARTED TidalConnect services.
May 27 01:29:59 hifi-studio volumio[11307]: info: Executing endpoint tc_connect
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
May 27 01:29:59 hifi-studio volumio[11307]: info: Connecting to TidalConnect
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::servicePushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::servicePushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:29:59 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:29:59 hifi-studio volumio[11307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect
May 27 01:29:59 hifi-studio volumio[11307]: info: [ASDebug] CurState: stop PrevState: stop
May 27 01:29:59 hifi-studio volumio[11307]: info: [ASDebug] CurState: stop PrevState: stop
May 27 01:30:01 hifi-studio volumio[11307]: info: MRS: Getting audio outputs on start
May 27 01:30:01 hifi-studio volumio[11307]: info: MRS: Requesting all other devices output
May 27 01:30:01 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPlay
May 27 01:30:01 hifi-studio volumio[11307]: info: CoreStateMachine::play index undefined
May 27 01:30:01 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService undefined
May 27 01:30:01 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:30:01 hifi-studio volumio[11307]: info: CoreStateMachine::startPlaybackTimer
May 27 01:30:01 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 0
May 27 01:30:01 hifi-studio volumio[11307]: info: [1748284201665] ControllerQobuz::clearAddPlayTrack
May 27 01:30:01 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand stop
May 27 01:30:01 hifi-studio volumio[11307]: info: sendMpdCommand stop took 1 milliseconds
May 27 01:30:01 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand clear
May 27 01:30:01 hifi-studio volumio[11307]: info:
May 27 01:30:01 hifi-studio volumio[11307]: ---------------------------- MPD announces system playlist update
May 27 01:30:01 hifi-studio volumio[11307]: info: Ignoring MPD Status Update
May 27 01:30:01 hifi-studio volumio[11307]: info: sendMpdCommand clear took 2 milliseconds
May 27 01:30:01 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/193171170"
May 27 01:30:01 hifi-studio volumio[11307]: error: updateQueue error: null
May 27 01:30:01 hifi-studio volumio[11307]: info: ------------------------------ 4ms
May 27 01:30:01 hifi-studio volumio[11307]: STREAMING PROXY: Handling url /?data=qobuz://song/193171170
May 27 01:30:01 hifi-studio volumio[11307]: info: Executing endpoint getStreamUrlqobuz
May 27 01:30:01 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioNext
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::next
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::stop
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService undefined
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::play index undefined
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService undefined
May 27 01:30:02 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 1
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::startPlaybackTimer
May 27 01:30:02 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 1
May 27 01:30:02 hifi-studio volumio[11307]: info: [1748284202394] ControllerQobuz::clearAddPlayTrack
May 27 01:30:02 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand stop
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::updateTrackBlock
May 27 01:30:02 hifi-studio volumio[11307]: info: CorePlayQueue::getTrackBlock
May 27 01:30:02 hifi-studio volumio[11307]: info: getStreamUrl took 914 milliseconds
May 27 01:30:02 hifi-studio volumio[11307]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=819350&eid=193171170&fmt=7&profile=raw&app_id=539451548&cid=2790391&etsp=1748287802&hmac=YUejE29CZoUkO_HXy_YIinsvCDk
May 27 01:30:02 hifi-studio volumio[11307]: info: TidalConnect service started!
May 27 01:30:02 hifi-studio volumio[11307]: [Metrics] CommandRouter: 29s 649.71ms
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::volumiosetStartupVolume
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::Close All Modals sent
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::Close All Modals sent
May 27 01:30:02 hifi-studio volumio[11307]: STREAMING PROXY: Response: 200, length: 56380373
May 27 01:30:02 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/193171170"
May 27 01:30:02 hifi-studio volumio[11307]: info: sendMpdCommand stop took 500 milliseconds
May 27 01:30:02 hifi-studio volumio[11307]: STREAMING PROXY: Client dropped request, destroying
May 27 01:30:02 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand clear
May 27 01:30:02 hifi-studio volumio[11307]: info:
May 27 01:30:02 hifi-studio volumio[11307]: ---------------------------- MPD announces system playlist update
May 27 01:30:02 hifi-studio volumio[11307]: info: Ignoring MPD Status Update
May 27 01:30:02 hifi-studio volumio[11307]: info:
May 27 01:30:02 hifi-studio volumio[11307]: ---------------------------- MPD announces system playlist update
May 27 01:30:02 hifi-studio volumio[11307]: info: Ignoring MPD Status Update
May 27 01:30:02 hifi-studio volumio[11307]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/193171170" took 3 milliseconds
May 27 01:30:02 hifi-studio volumio[11307]: info: sendMpdCommand clear took 3 milliseconds
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService mpd
May 27 01:30:02 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand play
May 27 01:30:02 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/193171171"
May 27 01:30:02 hifi-studio volumio[11307]: error: updateQueue error: null
May 27 01:30:02 hifi-studio volumio[11307]: error: updateQueue error: null
May 27 01:30:02 hifi-studio volumio[11307]: STREAMING PROXY: Handling url /?data=qobuz://song/193171171
May 27 01:30:02 hifi-studio volumio[11307]: info: ------------------------------ 5ms
May 27 01:30:02 hifi-studio volumio[11307]: info: ------------------------------ 5ms
May 27 01:30:02 hifi-studio volumio[11307]: info: sendMpdCommand play took 3 milliseconds
May 27 01:30:02 hifi-studio volumio[11307]: info: Executing endpoint getStreamUrlqobuz
May 27 01:30:02 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
May 27 01:30:03 hifi-studio volumio[11307]: info: getStreamUrl took 689 milliseconds
May 27 01:30:03 hifi-studio volumio[11307]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=819350&eid=193171171&fmt=7&profile=raw&app_id=539451548&cid=2790391&etsp=1748287803&hmac=BZ_mGcm3U07XZ00Zsa-48D58dJU
May 27 01:30:03 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 27 01:30:03 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 27 01:30:03 hifi-studio volumio[11307]: xcb_connection_has_error() returned true
May 27 01:30:03 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 27 01:30:03 hifi-studio volumio[11307]: STREAMING PROXY: Response: 200, length: 59825491
May 27 01:30:03 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/193171171"
May 27 01:30:03 hifi-studio volumio[11307]: STREAMING PROXY: Client dropped request, destroying
May 27 01:30:03 hifi-studio volumio[11307]: info:
May 27 01:30:03 hifi-studio volumio[11307]: ---------------------------- MPD announces system playlist update
May 27 01:30:03 hifi-studio volumio[11307]: info: Ignoring MPD Status Update
May 27 01:30:03 hifi-studio volumio[11307]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/193171171" took 8 milliseconds
May 27 01:30:03 hifi-studio volumio[11307]: info: CoreStateMachine::setConsumeUpdateService mpd
May 27 01:30:03 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand play
May 27 01:30:03 hifi-studio volumio[11307]: info: ------------------------------ 4ms
May 27 01:30:03 hifi-studio volumio[11307]: STREAMING PROXY: Handling url /?data=qobuz://song/193171171
May 27 01:30:03 hifi-studio volumio[11307]: info: sendMpdCommand play took 3 milliseconds
May 27 01:30:03 hifi-studio volumio[11307]: info: Executing endpoint getStreamUrlqobuz
May 27 01:30:03 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 27 01:30:04 hifi-studio volumio[11307]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:30:04 hifi-studio volumio[11307]: info: getStreamUrl took 512 milliseconds
May 27 01:30:04 hifi-studio volumio[11307]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=819350&eid=193171171&fmt=7&profile=raw&app_id=539451548&cid=2790391&etsp=1748287804&hmac=ruVXwssIOQDq6CYHD6s8zLbvR58
May 27 01:30:04 hifi-studio volumio[11307]: STREAMING PROXY: Response: 200, length: 59825491
May 27 01:30:04 hifi-studio volumio[11307]: info:
May 27 01:30:04 hifi-studio volumio[11307]: ---------------------------- MPD announces state update: player
May 27 01:30:04 hifi-studio volumio[11307]: info: ControllerMpd::getState
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand status
May 27 01:30:04 hifi-studio volumio[11307]: info:
May 27 01:30:04 hifi-studio volumio[11307]: ---------------------------- MPD announces state update: player
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand clearerror
May 27 01:30:04 hifi-studio volumio[11307]: info: sendMpdCommand status took 10 milliseconds
May 27 01:30:04 hifi-studio volumio[11307]: info: ControllerMpd::getState
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand status
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::parseState
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 27 01:30:04 hifi-studio volumio[11307]: info: sendMpdCommand clearerror took 3 milliseconds
May 27 01:30:04 hifi-studio volumio[11307]: info: sendMpdCommand status took 3 milliseconds
May 27 01:30:04 hifi-studio volumio[11307]: info: sendMpdCommand playlistinfo took 2 milliseconds
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::parseState
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 27 01:30:04 hifi-studio volumio[11307]: verbose: ControllerMpd::parseTrackInfo
May 27 01:30:04 hifi-studio volumio[11307]: info: ControllerMpd::pushState
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::servicePushState
May 27 01:30:04 hifi-studio volumio[11307]: info: CorePlayQueue::getTrack 1
May 27 01:30:04 hifi-studio volumio[11307]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":171,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"193171171","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/193171171","trackType":"qobuz"}
May 27 01:30:04 hifi-studio volumio[11307]: verbose: CURRENT POSITION 1
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreStateMachine::syncState stateService pause
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreStateMachine::syncState currentStatus stop
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreStateMachine::pushState
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioPushState
May 27 01:30:04 hifi-studio volumio[11307]: info: CoreCommandRouter::volumioGetState
May 27 01:30:04 hifi-studio volumio[11307]: info: ------------------------------ 31ms
May 27 01:30:04 hifi-studio volumio[11307]: info: [ASDebug] CurState: PrevState: stop
May 27 01:30:04 hifi-studio volumio[11307]: info: [ASDebug] Togle GPIO: ON
May 27 01:30:04 hifi-studio volumio[11307]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 27 01:30:04 hifi-studio volumio[11307]: Error: EBADF: bad file descriptor, write
May 27 01:30:04 hifi-studio volumio[11307]: at Object.writeSync (fs.js:711:3)
May 27 01:30:04 hifi-studio volumio[11307]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8)
May 27 01:30:04 hifi-studio volumio[11307]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23)
May 27 01:30:04 hifi-studio volumio[11307]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86)
May 27 01:30:04 hifi-studio volumio[11307]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 27 01:30:04 hifi-studio volumio[11307]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10)
May 27 01:30:04 hifi-studio volumio[11307]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12)
May 27 01:30:04 hifi-studio volumio[11307]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 27 01:30:04 hifi-studio volumio[11307]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 27 01:30:04 hifi-studio volumio[11307]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8)
May 27 01:30:04 hifi-studio volumio[11307]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 27 01:30:04 hifi-studio volumio[11307]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 27 01:30:04 hifi-studio volumio[11307]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12)
May 27 01:30:04 hifi-studio volumio[11307]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16)
May 27 01:30:04 hifi-studio volumio[11307]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 27 01:30:04 hifi-studio volumio[11307]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) {
May 27 01:30:04 hifi-studio volumio[11307]: errno: -9,
May 27 01:30:04 hifi-studio volumio[11307]: syscall: 'write',
May 27 01:30:04 hifi-studio volumio[11307]: code: 'EBADF'
May 27 01:30:04 hifi-studio volumio[11307]: }
May 27 01:30:04 hifi-studio volumio[11307]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 27 01:30:04 hifi-studio sudo[11778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-27 01:29
May 27 01:30:04 hifi-studio sudo[11778]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"