-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-31 19:55:34 CEST. -- May 31 19:54:00 volumiosypialnia volumio[923]: STREAMING PROXY: Starting server on port 3245 May 31 19:54:00 volumiosypialnia volumio[923]: Node JS runtime: 14 May 31 19:54:00 volumiosypialnia volumio[923]: info: MyVolumio token set successfully May 31 19:54:00 volumiosypialnia volumio[923]: info: MYVOLUMIO: Adding device May 31 19:54:00 volumiosypialnia volumio[923]: info: MYVOLUMIO: Evaluating Server May 31 19:54:00 volumiosypialnia volumio[923]: info: MyVolumio Plan changed: virtuoso May 31 19:54:00 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso May 31 19:54:00 volumiosypialnia volumio[923]: info: Removing browser output: myVolumio user plan is not superstar May 31 19:54:00 volumiosypialnia volumio[923]: info: Removing audio output: May 31 19:54:00 volumiosypialnia volumio[923]: info: MYVOLUMIO: Adding device May 31 19:54:00 volumiosypialnia volumio[923]: info: MYVOLUMIO: Evaluating Server May 31 19:54:00 volumiosypialnia volumio[923]: info: Remote config written successfully May 31 19:54:00 volumiosypialnia volumio[923]: info: Starting Tunnel 1 May 31 19:54:00 volumiosypialnia volumio[923]: info: Starting Tunnel Connection Checker May 31 19:54:00 volumiosypialnia volumio[923]: info: Completed starting MyVolumio Plugin May 31 19:54:01 volumiosypialnia systemd[1]: systemd-hostnamed.service: Succeeded. May 31 19:54:01 volumiosypialnia volumio[923]: info: MYVolumio Device enabled May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 31 19:54:01 volumiosypialnia volumio[923]: info: MyVolumio status changed May 31 19:54:01 volumiosypialnia volumio[923]: info: Streaming services startup May 31 19:54:01 volumiosypialnia volumio[923]: info: Starting Streaming Daemon May 31 19:54:01 volumiosypialnia sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 19:54:01 volumiosypialnia sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:01 volumiosypialnia volumio[923]: info: Setting Geolocation for MyVolumio to eu6 May 31 19:54:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:01 volumiosypialnia sudo[1497]: pam_unix(sudo:session): session closed for user root May 31 19:54:01 volumiosypialnia volumio[923]: error: Cannot start Volumio Streaming Daemon May 31 19:54:01 volumiosypialnia volumio[923]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 19:54:01 volumiosypialnia volumio[923]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 19:54:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 31 19:54:01 volumiosypialnia volumio[923]: info: [1748714041733] Starting BluetoothController May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 31 19:54:01 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 31 19:54:02 volumiosypialnia volumio[923]: info: Preparing CD Folders May 31 19:54:02 volumiosypialnia volumio[923]: info: Adding CD REST API Endpoints May 31 19:54:02 volumiosypialnia volumio[923]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 31 19:54:02 volumiosypialnia volumio[923]: info: Starting UDEV Watcher for CD May 31 19:54:02 volumiosypialnia volumio[923]: info: Detecting CD presence with UDEV May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 31 19:54:02 volumiosypialnia sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio_Sypialnia May 31 19:54:02 volumiosypialnia sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:02 volumiosypialnia sudo[1500]: pam_unix(sudo:session): session closed for user root May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 31 19:54:02 volumiosypialnia volumio[923]: info: Refreshing TIDAL token May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 31 19:54:02 volumiosypialnia volumio[923]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 31 19:54:02 volumiosypialnia volumio[923]: info: Stopping AccessToken refresher cron for QOBUZ May 31 19:54:02 volumiosypialnia sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 19:54:02 volumiosypialnia sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:02 volumiosypialnia volumio[923]: info: AccessToken refresher cron started for QOBUZ May 31 19:54:02 volumiosypialnia volumio[923]: info: Adding QOBUZ REST API Endpoints May 31 19:54:02 volumiosypialnia sudo[1503]: pam_unix(sudo:session): session closed for user root May 31 19:54:02 volumiosypialnia volumio[923]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 31 19:54:02 volumiosypialnia volumio[923]: info: Setting Geolocation for MyVolumio to eu6 May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:02 volumiosypialnia volumio[923]: info: Bluetooth name changed to Volumio_Sypialnia May 31 19:54:02 volumiosypialnia volumio[923]: info: Updating MyVolumio device info May 31 19:54:02 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:02 volumiosypialnia volumio[923]: info: Access Token successfully retrieved May 31 19:54:02 volumiosypialnia volumio[923]: info: Successfully Added MyVolumio device May 31 19:54:02 volumiosypialnia volumio[923]: info: Successfully Added MyVolumio device May 31 19:54:02 volumiosypialnia volumio[923]: info: Successfully Updated MyVolumio device May 31 19:54:02 volumiosypialnia volumio[923]: info: Successfully retrieved User Session From TIDAL May 31 19:54:03 volumiosypialnia volumio[923]: info: Successfully retrieved User Subscription From TIDAL May 31 19:54:03 volumiosypialnia volumio[923]: info: Adding TIDAL to Browse Sources May 31 19:54:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 19:54:03 volumiosypialnia volumio[923]: info: [1748714043217] CoreMusicLibrary::Adding element TIDAL May 31 19:54:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 19:54:03 volumiosypialnia volumio[923]: Cannot find translation for source TIDAL May 31 19:54:03 volumiosypialnia volumio[923]: info: Stopping AccessToken refresher cron May 31 19:54:03 volumiosypialnia volumio[923]: info: AccessToken refresher cron started May 31 19:54:03 volumiosypialnia volumio[923]: info: Adding TIDAL REST API Endpoints May 31 19:54:03 volumiosypialnia volumio[923]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 31 19:54:03 volumiosypialnia volumio[923]: info: Updating MyVolumio device info May 31 19:54:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:03 volumiosypialnia volumio[923]: info: Successfully Updated MyVolumio device May 31 19:54:05 volumiosypialnia systemd[1]: systemd-timedated.service: Succeeded. May 31 19:54:05 volumiosypialnia sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 31 19:54:05 volumiosypialnia sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:05 volumiosypialnia systemd[1]: Started Volumio Bluetooth Module. May 31 19:54:05 volumiosypialnia volumio[923]: info: TidalConnect service stoped! May 31 19:54:05 volumiosypialnia sudo[1509]: pam_unix(sudo:session): session closed for user root May 31 19:54:05 volumiosypialnia sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 31 19:54:05 volumiosypialnia sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:05 volumiosypialnia volumiobt[1511]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 31 19:54:05 volumiosypialnia sudo[1513]: pam_unix(sudo:session): session closed for user root May 31 19:54:05 volumiosypialnia volumio[923]: info: Volumio BT Module successfully started May 31 19:54:05 volumiosypialnia volumio[923]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 19:54:05 volumiosypialnia volumio[923]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 19:54:05 volumiosypialnia sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 19:54:05 volumiosypialnia sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:05 volumiosypialnia systemd[1]: Started Volumio Tidal Connect Service. May 31 19:54:05 volumiosypialnia sudo[1524]: pam_unix(sudo:session): session closed for user root May 31 19:54:05 volumiosypialnia volumio[923]: info: Executing endpoint tc_getconfig May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 19:54:05 volumiosypialnia vtcs[1526]: STARTING TidalConnect services, version: 1.4.0.34 May 31 19:54:05 volumiosypialnia vtcs[1526]: STARTED TidalConnect services. May 31 19:54:05 volumiosypialnia volumio[923]: info: Executing endpoint tc_connect May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 31 19:54:05 volumiosypialnia volumio[923]: info: Connecting to TidalConnect May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::servicePushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 19:54:05 volumiosypialnia volumio[923]: verbose: CURRENT POSITION 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::syncState stateService stop May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::syncState currentStatus stop May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:05 volumiosypialnia volumio[923]: info: No code May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::servicePushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 19:54:05 volumiosypialnia volumio[923]: verbose: CURRENT POSITION 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::syncState stateService stop May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::syncState currentStatus stop May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:05 volumiosypialnia volumio[923]: info: No code May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:05 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:05 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:06 volumiosypialnia sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 31 19:54:06 volumiosypialnia sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:06 volumiosypialnia systemd[1]: Started MyVolumio SSH Tunnel. May 31 19:54:06 volumiosypialnia sudo[1550]: pam_unix(sudo:session): session closed for user root May 31 19:54:06 volumiosypialnia volumio[923]: info: Remote SSH Started May 31 19:54:06 volumiosypialnia autossh[1553]: port set to 0, monitoring disabled May 31 19:54:06 volumiosypialnia autossh[1553]: starting ssh (count 1) May 31 19:54:06 volumiosypialnia autossh[1553]: ssh child pid is 1556 May 31 19:54:06 volumiosypialnia sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 31 19:54:06 volumiosypialnia sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:54:06 volumiosypialnia volumiobt[1511]: pulseaudio: no process found May 31 19:54:06 volumiosypialnia sudo[1557]: pam_unix(sudo:session): session closed for user root May 31 19:54:06 volumiosypialnia dbus-daemon[612]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1561 comm="/usr/bin/pulseaudio --high-priority=true ") May 31 19:54:06 volumiosypialnia systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 31 19:54:06 volumiosypialnia volumiossh-tunnel[1552]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. May 31 19:54:06 volumiosypialnia dbus-daemon[612]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Successfully called chroot. May 31 19:54:06 volumiosypialnia systemd[1]: Started RealtimeKit Scheduling Policy Service. May 31 19:54:06 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Successfully dropped privileges. May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Successfully limited resources. May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Running. May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Canary thread running. May 31 19:54:06 volumiosypialnia dbus-daemon[612]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1562 comm="/usr/lib/rtkit/rtkit-daemon ") May 31 19:54:06 volumiosypialnia rtkit-daemon[1562]: Watchdog thread running. May 31 19:54:06 volumiosypialnia volumio[923]: 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 31 19:54:06 volumiosypialnia systemd[1]: Starting Authorization Manager... May 31 19:54:06 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:54:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:54:06 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:06 volumiosypialnia polkitd[1566]: started daemon version 0.105 using authority implementation `local' version `0.105' May 31 19:54:06 volumiosypialnia dbus-daemon[612]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 31 19:54:06 volumiosypialnia systemd[1]: Started Authorization Manager. May 31 19:54:07 volumiosypialnia pulseaudio[1561]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 31 19:54:07 volumiosypialnia pulseaudio[1561]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 31 19:54:07 volumiosypialnia bluetoothd[769]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource May 31 19:54:07 volumiosypialnia bluetoothd[769]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink May 31 19:54:07 volumiosypialnia volumiobt[1511]: Applying permissions May 31 19:54:07 volumiosypialnia volumiobt[1511]: Setting BT discoverable and pairable May 31 19:54:07 volumiosypialnia pulseaudio[1561]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files May 31 19:54:07 volumiosypialnia kernel: Bluetooth: RFCOMM TTY layer initialized May 31 19:54:07 volumiosypialnia kernel: Bluetooth: RFCOMM socket layer initialized May 31 19:54:07 volumiosypialnia kernel: Bluetooth: RFCOMM ver 1.11 May 31 19:54:07 volumiosypialnia volumiobt[1511]: [CHG] Controller B8:27:EB:04:98:24 UUIDs: 00001112-0000-1000-8000-00805f9b34fb May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [55B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [117B blob data] May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# -e power on May 31 19:54:07 volumiosypialnia volumiobt[1511]: Invalid command in menu main: -e May 31 19:54:07 volumiosypialnia volumiobt[1511]: May 31 19:54:07 volumiosypialnia volumiobt[1511]: Use "help" for a list of available commands in a menu. May 31 19:54:07 volumiosypialnia volumiobt[1511]: Use "menu " if you want to enter any submenu. May 31 19:54:07 volumiosypialnia volumiobt[1511]: Use "back" if you want to return to menu main. May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# agent on May 31 19:54:07 volumiosypialnia volumiobt[1511]: Agent is already registered May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# discoverable on May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# pairable on May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# agent NoInputNoOutput May 31 19:54:07 volumiosypialnia volumiobt[1511]: Agent is already registered May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# default-agent May 31 19:54:07 volumiosypialnia volumiobt[1511]: [bluetooth]# quit May 31 19:54:07 volumiosypialnia volumiobt[1511]: [59B blob data] May 31 19:54:08 volumiosypialnia volumio[923]: info: TidalConnect service started! May 31 19:54:08 volumiosypialnia volumio[923]: [Metrics] CommandRouter: 31s 644.16ms May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumiosetStartupVolume May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:54:08 volumiosypialnia volumio[923]: info: VolumeController:: Setting startup Volume 30 May 31 19:54:08 volumiosypialnia volumio[923]: info: VolumeController::SetAlsaVolume30 May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::Close All Modals sent May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::Close All Modals sent May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:54:08 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:54:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:54:08 volumiosypialnia volumio[923]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 31 19:54:08 volumiosypialnia volumio[923]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 31 19:54:08 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:54:08 volumiosypialnia volumio[923]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 31 19:54:08 volumiosypialnia volumio[923]: aplay: main:828: audio open error: No such device May 31 19:54:09 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 31 19:54:09 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 19:54:09 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:54:09 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 31 19:54:09 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 31 19:54:12 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:54:12 volumiosypialnia volumio[923]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 31 19:54:12 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:54:12 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:54:15 volumiosypialnia volumio[923]: info: BOOT COMPLETED May 31 19:54:15 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 19:54:15 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:54:15 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 19:54:15 volumiosypialnia volumio-remote-updater[625]: No test mode May 31 19:54:15 volumiosypialnia volumio-remote-updater[625]: No alpha test mode May 31 19:54:15 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 19:54:16 volumiosypialnia volumio[923]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 19:54:16 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetBrowseSources May 31 19:54:17 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 19:54:37 volumiosypialnia ntpd[867]: Soliciting pool server 178.215.228.24 May 31 19:54:38 volumiosypialnia ntpd[867]: Soliciting pool server 212.127.78.21 May 31 19:54:38 volumiosypialnia ntpd[867]: Soliciting pool server 193.70.94.182 May 31 19:54:39 volumiosypialnia ntpd[867]: Soliciting pool server 91.210.128.220 May 31 19:54:39 volumiosypialnia ntpd[867]: Soliciting pool server 194.146.251.114 May 31 19:54:39 volumiosypialnia ntpd[867]: Soliciting pool server 193.25.222.136 May 31 19:54:40 volumiosypialnia ntpd[867]: Soliciting pool server 51.68.141.5 May 31 19:54:40 volumiosypialnia ntpd[867]: Soliciting pool server 213.222.217.11 May 31 19:54:40 volumiosypialnia ntpd[867]: Soliciting pool server 89.25.168.148 May 31 19:54:41 volumiosypialnia ntpd[867]: Soliciting pool server 89.161.47.136 May 31 19:54:41 volumiosypialnia ntpd[867]: Soliciting pool server 94.154.96.7 May 31 19:54:41 volumiosypialnia ntpd[867]: Soliciting pool server 162.159.200.123 May 31 19:54:42 volumiosypialnia ntpd[867]: Soliciting pool server 91.212.242.21 May 31 19:54:46 volumiosypialnia ntpd[867]: receive: Unexpected origin timestamp 0xebe5c0e5.94f81cca does not match aorg 0000000000.00000000 from server@213.222.217.11 xmt 0xebe5c0e6.c2433a45 May 31 19:54:46 volumiosypialnia ntpd[867]: receive: Unexpected origin timestamp 0xebe5c0e5.94f65af3 does not match aorg 0000000000.00000000 from server@89.25.168.148 xmt 0xebe5c0e6.c316aabc May 31 19:54:46 volumiosypialnia ntpd[867]: receive: Unexpected origin timestamp 0xebe5c0e5.94f1475a does not match aorg 0000000000.00000000 from server@89.161.47.136 xmt 0xebe5c0e6.c30bc7fe May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:01 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:01 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:01 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:01 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:01 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:02 volumiosypialnia volumio[923]: verbose: New Socket.io Connection to 192.168.31.249:3000 from 192.168.31.108 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 31 19:55:03 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:03 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:55:04 volumiosypialnia sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:04 volumiosypialnia sudo[1724]: pam_unix(sudo:session): session closed for user root May 31 19:55:04 volumiosypialnia sudo[1726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:55:04 volumiosypialnia sudo[1726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:04 volumiosypialnia sudo[1726]: pam_unix(sudo:session): session closed for user root May 31 19:55:04 volumiosypialnia volumio[923]: verbose: New Socket.io Connection to 192.168.31.249 from 192.168.31.108 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetVisibleSources May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 19:55:04 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:04 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: Listing playlists May 31 19:55:04 volumiosypialnia sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 19:55:04 volumiosypialnia sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 19:55:04 volumiosypialnia sudo[1731]: pam_unix(sudo:session): session closed for user root May 31 19:55:04 volumiosypialnia sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 19:55:04 volumiosypialnia sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:04 volumiosypialnia sudo[1733]: pam_unix(sudo:session): session closed for user root May 31 19:55:04 volumiosypialnia volumio[923]: verbose: New Socket.io Connection to 192.168.31.249 from 192.168.31.108 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetVisibleSources May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 19:55:04 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:04 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:04 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:04 volumiosypialnia volumio[923]: info: Listing playlists May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:55:04 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:55:06 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:06 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:06 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:06 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 19:55:07 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 19:55:07 volumiosypialnia volumio[923]: Invalid card number. May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 19:55:07 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 19:55:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:55:08 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:08 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:08 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:08 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:55:10 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:10 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:10 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 19:55:10 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:10 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:10 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:10 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:14 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 19:55:16 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:16 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:16 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:16 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:16 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:16 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 19:55:23 volumiosypialnia volumio[923]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 19:55:23 volumiosypialnia volumio[923]: info: Received Get System Version May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 19:55:23 volumiosypialnia volumio[923]: info: Received Get System Info May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 19:55:23 volumiosypialnia volumio[923]: info: Discovery: Getting this device information May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:23 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:23 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 19:55:31 volumiosypialnia volumio[923]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 31 19:55:31 volumiosypialnia volumio[923]: info: Preparing to save Alsa Options, stopping services first May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioGetState May 31 19:55:31 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPause May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreStateMachine::pause May 31 19:55:31 volumiosypialnia volumio[923]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Khadas Tone Control"},"i2s":true,"i2sid":{"value":"audiophonics-es9028q2m-dac","label":"Audiophonics I-Sabre ES9028Q2M"}} May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:31 volumiosypialnia volumio[923]: info: Enabling I2S DAC: Audiophonics I-Sabre ES9028Q2M May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:31 volumiosypialnia sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 19:55:31 volumiosypialnia sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:31 volumiosypialnia sudo[1813]: pam_unix(sudo:session): session closed for user root May 31 19:55:31 volumiosypialnia volumio[923]: info: No Overlays Loaded May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:31 volumiosypialnia sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i-sabre-q2m May 31 19:55:31 volumiosypialnia sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/#sound-dai-cells May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/compatible May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/reg May 31 19:55:31 volumiosypialnia kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/i-sabre-codec@48/status May 31 19:55:31 volumiosypialnia sudo[1816]: pam_unix(sudo:session): session closed for user root May 31 19:55:31 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:31 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 31 19:55:31 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:31 volumiosypialnia volumio[923]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 19:55:32 volumiosypialnia volumio[923]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 19:55:32 volumiosypialnia volumio[923]: info: Disabling external Volume Control May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 19:55:32 volumiosypialnia volumio[923]: info: Enable softmixer device for audio device undefined May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 31 19:55:32 volumiosypialnia volumio[923]: info: I2S Param [object Object] successfully enabled May 31 19:55:32 volumiosypialnia volumio[923]: info: Software Volume ALSA configuration written May 31 19:55:32 volumiosypialnia volumio[923]: info: Preparing to generate the ALSA configuration file May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia volumio[923]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 31 19:55:32 volumiosypialnia volumio[923]: info: Reading ALSA contributions from plugins. May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 19:55:32 volumiosypialnia volumio[923]: info: Getting Alsa Cards List without I2S DAC May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 19:55:32 volumiosypialnia volumio[923]: info: VolumeController:: Volume=76 Mute =true May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreStateMachine::pushState May 31 19:55:32 volumiosypialnia volumio[923]: info: CorePlayQueue::getTrack 0 May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::volumioPushState May 31 19:55:32 volumiosypialnia volumio[923]: info: Asound.conf file written May 31 19:55:32 volumiosypialnia sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 19:55:32 volumiosypialnia sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia sudo[1855]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Volume:0' : Remote I/O error May 31 19:55:32 volumiosypialnia volumio[923]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error May 31 19:55:32 volumiosypialnia volumio[923]: info: Output device has changed, restarting MPD May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 May 31 19:55:32 volumiosypialnia kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 May 31 19:55:32 volumiosypialnia volumio[923]: info: Output device has changed, restarting Shairport Sync May 31 19:55:32 volumiosypialnia sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:32 volumiosypialnia sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia sudo[1860]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 19:55:32 volumiosypialnia sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia systemd[1]: musicservicesshield.service: Succeeded. May 31 19:55:32 volumiosypialnia systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 31 19:55:32 volumiosypialnia systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia systemd[1]: Stopping Music Player Daemon... May 31 19:55:32 volumiosypialnia vtcs[1526]: [2025-05-31 19:55:32.623] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 31 19:55:32 volumiosypialnia vtcs[1526]: [2025-05-31 19:55:32.625] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 31 19:55:32 volumiosypialnia systemd[1]: mpd.service: Succeeded. May 31 19:55:32 volumiosypialnia volumio[923]: info: Output device has changed, restarting MPD May 31 19:55:32 volumiosypialnia systemd[1]: Stopped Music Player Daemon. May 31 19:55:32 volumiosypialnia sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 19:55:32 volumiosypialnia sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia volumio[923]: info: Output device has changed, restarting Shairport Sync May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:32 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:32 volumiosypialnia systemd[1]: Starting Music Player Daemon... May 31 19:55:32 volumiosypialnia sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 19:55:32 volumiosypialnia sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia systemd[1]: Stopping Volumio Tidal Connect Service... May 31 19:55:32 volumiosypialnia systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 31 19:55:32 volumiosypialnia systemd[1]: vtcs.service: Succeeded. May 31 19:55:32 volumiosypialnia systemd[1]: Stopped Volumio Tidal Connect Service. May 31 19:55:32 volumiosypialnia sudo[1872]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia sudo[1869]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 19:55:32 volumiosypialnia sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 19:55:32 volumiosypialnia volumio[923]: xcb_connection_has_error() returned true May 31 19:55:32 volumiosypialnia systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 31 19:55:32 volumiosypialnia systemd[1]: mpd.service: Succeeded. May 31 19:55:32 volumiosypialnia systemd[1]: Stopped Music Player Daemon. May 31 19:55:32 volumiosypialnia systemd[1]: Starting Music Player Daemon... May 31 19:55:32 volumiosypialnia sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 19:55:32 volumiosypialnia sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 19:55:32 volumiosypialnia sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 19:55:32 volumiosypialnia sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 19:55:32 volumiosypialnia sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 19:55:32 volumiosypialnia sudo[1884]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:55:32 volumiosypialnia sudo[1889]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia sudo[1886]: pam_unix(sudo:session): session closed for user root May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:55:32 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 19:55:33 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 19:55:33 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:55:33 volumiosypialnia sudo[1883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 19:55:33 volumiosypialnia sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:33 volumiosypialnia sudo[1883]: pam_unix(sudo:session): session closed for user root May 31 19:55:33 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 31 19:55:33 volumiosypialnia volumio[923]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 19:55:33 volumiosypialnia volumio[923]: info: MPD Permissions set May 31 19:55:33 volumiosypialnia volumio[923]: info: MPD Permissions set May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 19:55:33 volumiosypialnia volumio[923]: info: Starting Shairport Sync May 31 19:55:33 volumiosypialnia volumio[923]: info: Starting Shairport Sync May 31 19:55:33 volumiosypialnia sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 19:55:33 volumiosypialnia sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:33 volumiosypialnia sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 19:55:33 volumiosypialnia sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:33 volumiosypialnia systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 19:55:33 volumiosypialnia systemd[1]: shairport-sync.service: Succeeded. May 31 19:55:33 volumiosypialnia systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 19:55:33 volumiosypialnia volumio[923]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 31 19:55:33 volumiosypialnia volumio[923]: info: PLUGIN onReboot : networkfs May 31 19:55:33 volumiosypialnia sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/music May 31 19:55:33 volumiosypialnia sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 19:55:33 volumiosypialnia systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 19:55:33 volumiosypialnia sudo[1906]: pam_unix(sudo:session): session closed for user root May 31 19:55:33 volumiosypialnia sudo[1908]: pam_unix(sudo:session): session closed for user root May 31 19:55:33 volumiosypialnia sudo[1913]: pam_unix(sudo:session): session closed for user root May 31 19:55:33 volumiosypialnia systemd[1]: mnt-NAS-music.mount: Succeeded. May 31 19:55:33 volumiosypialnia volumio[923]: info: PLUGIN onReboot : audiophonicsonoff May 31 19:55:33 volumiosypialnia volumio[923]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:55:33 volumiosypialnia volumio[923]: TypeError: Cannot read property 'writeSync' of undefined May 31 19:55:33 volumiosypialnia volumio[923]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 31 19:55:33 volumiosypialnia volumio[923]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 31 19:55:33 volumiosypialnia volumio[923]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 31 19:55:33 volumiosypialnia volumio[923]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 31 19:55:33 volumiosypialnia volumio[923]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 31 19:55:33 volumiosypialnia volumio[923]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 31 19:55:33 volumiosypialnia volumio[923]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) May 31 19:55:33 volumiosypialnia volumio[923]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) May 31 19:55:33 volumiosypialnia volumio[923]: at Socket.emit (events.js:315:20) May 31 19:55:33 volumiosypialnia volumio[923]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 19:55:33 volumiosypialnia volumio[923]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 31 19:55:33 volumiosypialnia volumio[923]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 19:55:34 volumiosypialnia sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 19:54 May 31 19:55:34 volumiosypialnia sudo[1927]: 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"