-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Wed 2025-03-12 16:48:53 PDT. --
Mar 12 16:47:01 volumio-studio volumio[829]: info: Starting Streaming Service Transparent Proxy
Mar 12 16:47:01 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 12 16:47:01 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 12 16:47:01 volumio-studio volumio[829]: info: Streaming services startup
Mar 12 16:47:01 volumio-studio volumio[829]: info: Starting Streaming Daemon
Mar 12 16:47:01 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 12 16:47:02 volumio-studio sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 12 16:47:02 volumio-studio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:47:03 volumio-studio ntpd[642]: Soliciting pool server 162.159.200.123
Mar 12 16:47:03 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 12 16:47:03 volumio-studio volumio[829]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory
Mar 12 16:47:03 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:03 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 12 16:47:03 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:03 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 12 16:47:03 volumio-studio sudo[1640]: pam_unix(sudo:session): session closed for user root
Mar 12 16:47:03 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.7
Mar 12 16:47:03 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.4
Mar 12 16:47:03 volumio-studio ntpd[642]: Soliciting pool server 149.28.200.179
Mar 12 16:47:03 volumio-studio volumio[829]: error: Cannot start Volumio Streaming Daemon
Mar 12 16:47:03 volumio-studio volumio[829]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 12 16:47:03 volumio-studio volumio[829]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4
Mar 12 16:47:03 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:47:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741823218 101
Mar 12 16:47:03 volumio-studio volumio[829]: 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: 5
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7
Mar 12 16:47:03 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Mar 12 16:47:04 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 12 16:47:04 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Mar 12 16:47:04 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Mar 12 16:47:04 volumio-studio volumio[829]: info: Discovery: Connected to remote: 172.16.0.7
Mar 12 16:47:04 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Mar 12 16:47:04 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:04 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:04 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:04 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:04 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:04 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:04 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Mar 12 16:47:05 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 12 16:47:05 volumio-studio volumio-remote-updater[507]: No test mode
Mar 12 16:47:05 volumio-studio volumio-remote-updater[507]: No alpha test mode
Mar 12 16:47:05 volumio-studio volumio[829]: info: Discovery: Connected to remote: 172.16.0.4
Mar 12 16:47:05 volumio-studio volumio[829]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 12 16:47:05 volumio-studio ntpd[642]: Soliciting pool server 129.250.35.250
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 12 16:47:06 volumio-studio volumio[829]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Improved translations
NEW ADDITIONS
- Add DSD-quirk for several USB DACs
- Enable install-to-NVME on CM5 and newer RPi5 revisions
- Fix potential issues with QOBUZ and TIDAL Streaming on slow connections
","title":"Update v3.795","updateavailable":true}
Mar 12 16:47:06 volumio-studio ntpd[642]: Soliciting pool server 208.67.72.43
Mar 12 16:47:06 volumio-studio ntpd[642]: Soliciting pool server 65.19.142.137
Mar 12 16:47:06 volumio-studio ntpd[642]: Soliciting pool server 162.159.200.1
Mar 12 16:47:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 12 16:47:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:07 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 12 16:47:07 volumio-studio volumio[829]: info: Received Get System Info
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 12 16:47:07 volumio-studio volumio[829]: info: Discovery: Getting this device information
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:07 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:47:07 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:47:07 volumio-studio volumio[829]: info: Listing playlists
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetQueue
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreStateMachine::getQueue
Mar 12 16:47:07 volumio-studio volumio[829]: info: CorePlayQueue::getQueue
Mar 12 16:47:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 12 16:47:07 volumio-studio volumio[829]: info: MyVolumio token set successfully
Mar 12 16:47:07 volumio-studio volumio[829]: info: MYVOLUMIO: Adding device
Mar 12 16:47:07 volumio-studio volumio[829]: info: MYVOLUMIO: Evaluating Server
Mar 12 16:47:07 volumio-studio ntpd[642]: Soliciting pool server 23.150.41.123
Mar 12 16:47:07 volumio-studio ntpd[642]: Soliciting pool server 216.66.48.42
Mar 12 16:47:07 volumio-studio ntpd[642]: Soliciting pool server 45.83.234.123
Mar 12 16:47:08 volumio-studio ntpd[642]: Soliciting pool server 216.229.4.69
Mar 12 16:47:08 volumio-studio ntpd[642]: Soliciting pool server 23.111.186.186
Mar 12 16:47:08 volumio-studio volumio[829]: STREAMING PROXY: Starting server on port 3245
Mar 12 16:47:08 volumio-studio volumio[829]: Node JS runtime: 14
Mar 12 16:47:08 volumio-studio volumio[829]: info: MyVolumio Plan changed: premium
Mar 12 16:47:08 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Mar 12 16:47:08 volumio-studio volumio[829]: info: Removing browser output: myVolumio user plan is not superstar
Mar 12 16:47:08 volumio-studio volumio[829]: info: Removing audio output:
Mar 12 16:47:08 volumio-studio volumio[829]: info: MYVOLUMIO: Adding device
Mar 12 16:47:08 volumio-studio volumio[829]: info: MYVOLUMIO: Evaluating Server
Mar 12 16:47:09 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13
Mar 12 16:47:09 volumio-studio ntpd[642]: Soliciting pool server 75.72.171.171
Mar 12 16:47:09 volumio-studio volumio[829]: info: Remote config written successfully
Mar 12 16:47:09 volumio-studio volumio[829]: info: Starting Tunnel 1
Mar 12 16:47:09 volumio-studio volumio[829]: info: Starting Tunnel Connection Checker
Mar 12 16:47:09 volumio-studio volumio[829]: info: Completed starting MyVolumio Plugin
Mar 12 16:47:09 volumio-studio volumio[829]: info: MYVolumio Device enabled
Mar 12 16:47:09 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Mar 12 16:47:09 volumio-studio volumio[829]: info: MyVolumio status changed
Mar 12 16:47:09 volumio-studio volumio[829]: info: Streaming services startup
Mar 12 16:47:09 volumio-studio volumio[829]: info: Starting Streaming Daemon
Mar 12 16:47:10 volumio-studio ntpd[642]: Soliciting pool server 2607:f130:0:103:ff:ff:8de7:3fa1
Mar 12 16:47:13 volumio-studio ntpd[642]: receive: Unexpected origin timestamp 0xeb7c9b7f.9ae5ed85 does not match aorg 0000000000.00000000 from server@23.111.186.186 xmt 0xeb7c9b80.991a67e0
Mar 12 16:47:13 volumio-studio ntpd[642]: receive: Unexpected origin timestamp 0xeb7c9b7f.9aedc4ff does not match aorg 0000000000.00000000 from server@216.66.48.42 xmt 0xeb7c9b80.9b4a8880
Mar 12 16:47:13 volumio-studio volumio[829]: info: Setting Geolocation for MyVolumio to us2
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio-remote-updater[507]: No test mode
Mar 12 16:47:13 volumio-studio volumio-remote-updater[507]: No alpha test mode
Mar 12 16:47:13 volumio-studio volumio[829]: info: Setting Geolocation for MyVolumio to us2
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Mar 12 16:47:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:47:14 volumio-studio sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 12 16:47:14 volumio-studio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:47:14 volumio-studio volumio-remote-updater[507]: No test mode
Mar 12 16:47:14 volumio-studio volumio-remote-updater[507]: No alpha test mode
Mar 12 16:47:14 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Mar 12 16:47:14 volumio-studio volumio-remote-updater[507]: No test mode
Mar 12 16:47:14 volumio-studio volumio-remote-updater[507]: No alpha test mode
Mar 12 16:47:15 volumio-studio volumio-remote-updater[507]: No test mode
Mar 12 16:47:15 volumio-studio volumio-remote-updater[507]: No alpha test mode
Mar 12 16:47:19 volumio-studio sudo[1701]: pam_unix(sudo:session): session closed for user root
Mar 12 16:47:32 volumio-studio volumio[829]: info: [1741823251999] Starting BluetoothController
Mar 12 16:47:34 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Mar 12 16:47:35 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:47:35] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006]
Mar 12 16:47:40 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:47:40] [connect] Successful connection
Mar 12 16:47:56 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:47:55] [connect] Successful connection
Mar 12 16:48:01 volumio-studio volumiologrotate[496]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory
Mar 12 16:48:01 volumio-studio volumiologrotate[496]: ls: cannot access 'STUDIO': No such file or directory
Mar 12 16:48:04 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Mar 12 16:48:04 volumio-studio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Mar 12 16:48:04 volumio-studio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:04 volumio-studio sudo[1843]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:04 volumio-studio volumio[829]: info: MRS: MultiRoom plugin initialized
Mar 12 16:48:04 volumio-studio volumio[829]: info: MRS: STOPPING SNAPCLIENT
Mar 12 16:48:04 volumio-studio volumio[829]: info: MRS: Snap server stop
Mar 12 16:48:04 volumio-studio sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Mar 12 16:48:04 volumio-studio sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:04 volumio-studio volumio[829]: info: MRS: STOPPING volumioStreaming
Mar 12 16:48:04 volumio-studio sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Mar 12 16:48:04 volumio-studio sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:04 volumio-studio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Mar 12 16:48:04 volumio-studio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Mar 12 16:48:05 volumio-studio sudo[1867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Mar 12 16:48:05 volumio-studio sudo[1867]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:05 volumio-studio sudo[1867]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Mar 12 16:48:05 volumio-studio sudo[1859]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:05 volumio-studio sudo[1862]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:05 volumio-studio sudo[1865]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Mar 12 16:48:05 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Mar 12 16:48:06 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Mar 12 16:48:06 volumio-studio volumio[829]: info: Preparing to generate the ALSA configuration file
Mar 12 16:48:06 volumio-studio volumio[829]: info: Updating MyVolumio device info
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: Updating MyVolumio device info
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: UPDATER: Scheduling automatic update
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 12 16:48:06 volumio-studio volumio[829]: info: UPDATER: Auto update will take place at: Thu Mar 13 2025 05:56:54 GMT-0700 (Pacific Daylight Time)
Mar 12 16:48:06 volumio-studio volumio[829]: error: MyVolumio Plugins failed to start in a timely fashion
Mar 12 16:48:06 volumio-studio volumio[829]: [Metrics] CommandRouter: 113s 570.33ms
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::Close All Modals sent
Mar 12 16:48:06 volumio-studio volumio[829]: info: CoreCommandRouter::Close All Modals sent
Mar 12 16:48:06 volumio-studio volumio[829]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Mar 12 16:48:06 volumio-studio volumio[829]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 12 16:48:06 volumio-studio volumio[829]: info: Reading ALSA contributions from plugins.
Mar 12 16:48:06 volumio-studio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: Removed streaming files
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: volumioStreaming STOPPED
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: SNAPSERVER STOPPED
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: SNAPCLIENT STOPPED
Mar 12 16:48:06 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.7
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: Pushing multiroomSync output for this device
Mar 12 16:48:06 volumio-studio volumio[829]: info: MRS: Pushing multiroomSync output
Mar 12 16:48:06 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:06 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:06 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.7
Mar 12 16:48:06 volumio-studio volumio[829]: error: Cannot start Volumio Streaming Daemon
Mar 12 16:48:06 volumio-studio volumio[829]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 12 16:48:06 volumio-studio volumio[829]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 12 16:48:07 volumio-studio volumio[829]: error: Failed to add MyVolumio device: undefined
Mar 12 16:48:07 volumio-studio volumio[829]: error: Failed to add MyVolumio device: undefined
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.7 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14
Mar 12 16:48:07 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27:3000 from 172.16.0.4 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15
Mar 12 16:48:07 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.4
Mar 12 16:48:07 volumio-studio volumio[829]: info: Discovery: Disconnected from remote: 172.16.0.4
Mar 12 16:48:07 volumio-studio volumio[829]: error: error
Mar 12 16:48:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 12 16:48:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 12 16:48:07 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 12 16:48:08 volumio-studio volumio[829]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined
Mar 12 16:48:08 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo
Mar 12 16:48:08 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16
Mar 12 16:48:08 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Mar 12 16:48:08 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Mar 12 16:48:08 volumio-studio volumio[829]: info: MRS: Pushing audio output on request
Mar 12 16:48:08 volumio-studio volumio[829]: info: MRS: Pushing multiroomSync output
Mar 12 16:48:08 volumio-studio volumio[829]: info: Discovery: Connected to remote: 172.16.0.7
Mar 12 16:48:08 volumio-studio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Mar 12 16:48:08 volumio-studio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:08 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:08 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:08 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:08 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:08 volumio-studio systemd[1]: Started MyVolumio SSH Tunnel.
Mar 12 16:48:08 volumio-studio sudo[1878]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:08 volumio-studio volumio[829]: info: Remote SSH Started
Mar 12 16:48:09 volumio-studio autossh[1881]: port set to 0, monitoring disabled
Mar 12 16:48:09 volumio-studio autossh[1881]: starting ssh (count 1)
Mar 12 16:48:09 volumio-studio autossh[1881]: ssh child pid is 1884
Mar 12 16:48:09 volumio-studio volumiossh-tunnel[1880]: Warning: Permanently added '[us2.myvolumio.org]:2222,[137.184.235.84]:2222' (RSA) to the list of known hosts.
Mar 12 16:48:10 volumio-studio volumio[829]: info: MRS: Adding multiroomSync output
Mar 12 16:48:10 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:10 volumio-studio volumio[829]: info: MRS: Pushing multiroomSync output
Mar 12 16:48:10 volumio-studio volumio[829]: info: MRS: Pushing audio output on request
Mar 12 16:48:10 volumio-studio volumio[829]: info: MRS: Pushing multiroomSync output
Mar 12 16:48:10 volumio-studio volumio[829]: info: Discovery: Connected to remote: 172.16.0.4
Mar 12 16:48:10 volumio-studio volumio[829]: info: Successfully Updated MyVolumio device
Mar 12 16:48:10 volumio-studio volumio[829]: info: Successfully Updated MyVolumio device
Mar 12 16:48:10 volumio-studio volumio[829]: info: Listing playlists
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreStateMachine::getQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CorePlayQueue::getQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:10 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:10 volumio-studio volumio[829]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo
Mar 12 16:48:10 volumio-studio volumio[829]: info: Listing playlists
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreStateMachine::getQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CorePlayQueue::getQueue
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 12 16:48:10 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:10 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:10 volumio-studio volumio[829]: info: Asound.conf file written
Mar 12 16:48:10 volumio-studio sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Mar 12 16:48:10 volumio-studio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:10 volumio-studio sudo[1902]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:10 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:48:10] [connect] Successful connection
Mar 12 16:48:11 volumio-studio volumio[829]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy
Mar 12 16:48:11 volumio-studio volumio[829]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy
Mar 12 16:48:11 volumio-studio volumio[829]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy
Mar 12 16:48:11 volumio-studio volumio[829]: info: Output device has changed, restarting MPD
Mar 12 16:48:11 volumio-studio sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 12 16:48:11 volumio-studio sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:11 volumio-studio volumio[829]: info: Output device has changed, restarting Shairport Sync
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:11 volumio-studio sudo[1908]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:11 volumio-studio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 12 16:48:11 volumio-studio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:11 volumio-studio systemd[1]: Stopping Music Player Daemon...
Mar 12 16:48:11 volumio-studio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 12 16:48:11 volumio-studio volumio[829]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Mar 12 16:48:11 volumio-studio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Studio
Mar 12 16:48:11 volumio-studio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:11 volumio-studio sudo[1919]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:11 volumio-studio volumio[829]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding METAVOLUMIO REST API Endpoints
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Mar 12 16:48:11 volumio-studio volumio[829]: info: Preparing CD Folders
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding CD REST API Endpoints
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Mar 12 16:48:11 volumio-studio volumio[829]: info: Starting UDEV Watcher for CD
Mar 12 16:48:11 volumio-studio volumio[829]: info: Detecting CD presence with UDEV
Mar 12 16:48:11 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding inputs REST Endpoints
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Mar 12 16:48:11 volumio-studio volumio[829]: info: Scanning Audio Inputs
Mar 12 16:48:11 volumio-studio volumio[829]: info: Checking against Known Cards name
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding Server instance for streaming
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Mar 12 16:48:11 volumio-studio volumio[829]: error: Hi Res Audio Failed Login: Missing Login Data
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding HIGHRESAUDIO REST API Endpoints
Mar 12 16:48:11 volumio-studio volumio[829]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Mar 12 16:48:11 volumio-studio volumio[829]: info: Refreshing TIDAL token
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Mar 12 16:48:11 volumio-studio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Mar 12 16:48:11 volumio-studio volumio[829]: info: Stopping AccessToken refresher cron for QOBUZ
Mar 12 16:48:12 volumio-studio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 12 16:48:12 volumio-studio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:12 volumio-studio sudo[1927]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:12 volumio-studio volumio[829]: info: AccessToken refresher cron started for QOBUZ
Mar 12 16:48:12 volumio-studio volumio[829]: info: Adding QOBUZ REST API Endpoints
Mar 12 16:48:12 volumio-studio volumio[829]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Mar 12 16:48:12 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17
Mar 12 16:48:12 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18
Mar 12 16:48:12 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19
Mar 12 16:48:12 volumio-studio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20
Mar 12 16:48:12 volumio-studio volumio[829]: 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: 21
Mar 12 16:48:12 volumio-studio volumio[829]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Mar 12 16:48:12 volumio-studio volumio[829]: info: Bluetooth name changed to Volumio Studio
Mar 12 16:48:12 volumio-studio volumio[829]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Mar 12 16:48:12 volumio-studio volumio[829]: info: MPD Permissions set
Mar 12 16:48:12 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:48:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741823290 101
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 12 16:48:12 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 12 16:48:13 volumio-studio volumio[829]: verbose: New Socket.io Connection to 172.16.0.27 from 172.16.0.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 22
Mar 12 16:48:13 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:13 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:13 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:13 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:13 volumio-studio volumio[829]: info: BOOT COMPLETED
Mar 12 16:48:13 volumio-studio volumio[829]: info: Starting Shairport Sync
Mar 12 16:48:13 volumio-studio volumio[829]: info: MRS: Found cast device: SHIELD-Android-TV-ba74f2ec38fdb4d006dc8163624b72a2
Mar 12 16:48:13 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:13 volumio-studio volumio[829]: info: Access Token successfully retrieved
Mar 12 16:48:13 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 12 16:48:13 volumio-studio volumio[829]: info: MRS: Found cast device: SHIELD-Android-TV-3aec8a7239a2efea2381b7ce8e72fbff
Mar 12 16:48:13 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:13 volumio-studio volumio[829]: info: Preparing to generate the ALSA configuration file
Mar 12 16:48:14 volumio-studio sudo[1938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 12 16:48:14 volumio-studio sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:14 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 12 16:48:14 volumio-studio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 12 16:48:15 volumio-studio volumio[829]: info: MRS: Found cast device: Smart-TV-Pro-5284531cf4973a2388cc09932da98dfa
Mar 12 16:48:15 volumio-studio volumio[829]: info: Adding audio output:
Mar 12 16:48:15 volumio-studio volumio[829]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Mar 12 16:48:15 volumio-studio volumio[829]: info: Reading ALSA contributions from plugins.
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:15 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 12 16:48:15 volumio-studio volumio[829]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Mar 12 16:48:15 volumio-studio volumio[829]: info: Received Get System Info
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 12 16:48:15 volumio-studio volumio[829]: info: Discovery: Getting this device information
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:15 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetState
Mar 12 16:48:15 volumio-studio volumio[829]: info: CorePlayQueue::getTrack 0
Mar 12 16:48:15 volumio-studio volumio[829]: info: Listing playlists
Mar 12 16:48:15 volumio-studio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Mar 12 16:48:15 volumio-studio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreCommandRouter::volumioGetQueue
Mar 12 16:48:15 volumio-studio volumio[829]: info: CoreStateMachine::getQueue
Mar 12 16:48:15 volumio-studio volumio[829]: info: CorePlayQueue::getQueue
Mar 12 16:48:15 volumio-studio systemd[1]: Started Volumio Bluetooth Module.
Mar 12 16:48:16 volumio-studio sudo[1941]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:17 volumio-studio systemd[1]: shairport-sync.service: Succeeded.
Mar 12 16:48:17 volumio-studio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 12 16:48:17 volumio-studio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 12 16:48:18 volumio-studio sudo[1938]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:18 volumio-studio sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
Mar 12 16:48:18 volumio-studio sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:18 volumio-studio volumiobt[1943]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
Mar 12 16:48:18 volumio-studio sudo[1945]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:27 volumio-studio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
Mar 12 16:48:27 volumio-studio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:29 volumio-studio volumiobt[1943]: pulseaudio: no process found
Mar 12 16:48:27 volumio-studio sudo[1949]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:31 volumio-studio dbus-daemon[502]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.29' (uid=1000 pid=1973 comm="/usr/bin/pulseaudio --high-priority=true ")
Mar 12 16:48:32 volumio-studio systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Mar 12 16:48:32 volumio-studio dbus-daemon[502]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Mar 12 16:48:32 volumio-studio systemd[1]: Started RealtimeKit Scheduling Policy Service.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Successfully called chroot.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Successfully dropped privileges.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Successfully limited resources.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Running.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Canary thread running.
Mar 12 16:48:32 volumio-studio rtkit-daemon[1974]: Watchdog thread running.
Mar 12 16:48:32 volumio-studio dbus-daemon[502]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.30' (uid=0 pid=1974 comm="/usr/lib/rtkit/rtkit-daemon ")
Mar 12 16:48:33 volumio-studio systemd[1]: Starting Authorization Manager...
Mar 12 16:48:35 volumio-studio volumio[829]: info: MRS: Getting audio outputs on start
Mar 12 16:48:35 volumio-studio volumio[829]: info: MRS: Requesting all other devices output
Mar 12 16:48:37 volumio-studio polkitd[1978]: started daemon version 0.105 using authority implementation `local' version `0.105'
Mar 12 16:48:37 volumio-studio dbus-daemon[502]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Mar 12 16:48:37 volumio-studio systemd[1]: Started Authorization Manager.
Mar 12 16:48:40 volumio-studio pulseaudio[1973]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar 12 16:48:40 volumio-studio pulseaudio[1973]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar 12 16:48:40 volumio-studio bluetoothd[637]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSource
Mar 12 16:48:40 volumio-studio bluetoothd[637]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSink
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Applying permissions
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Setting BT discoverable and pairable
Mar 12 16:48:40 volumio-studio pulseaudio[1973]: 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
Mar 12 16:48:40 volumio-studio systemd[1]: mpd.service: Succeeded.
Mar 12 16:48:40 volumio-studio systemd[1]: Stopped Music Player Daemon.
Mar 12 16:48:40 volumio-studio systemd[1]: Starting Music Player Daemon...
Mar 12 16:48:40 volumio-studio kernel: Bluetooth: RFCOMM TTY layer initialized
Mar 12 16:48:40 volumio-studio kernel: Bluetooth: RFCOMM socket layer initialized
Mar 12 16:48:40 volumio-studio kernel: Bluetooth: RFCOMM ver 1.11
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [CHG] Controller 2C:CF:67:90:B3:94 Class: 0x0008041c
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [55B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [91B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [117B blob data]
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# -e power on
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Invalid command in menu main: -e
Mar 12 16:48:40 volumio-studio volumiobt[1943]:
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Use "help" for a list of available commands in a menu.
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Use "menu " if you want to enter any submenu.
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Use "back" if you want to return to menu main.
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# agent on
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Agent is already registered
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# discoverable on
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# pairable on
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# agent NoInputNoOutput
Mar 12 16:48:40 volumio-studio volumiobt[1943]: Agent is already registered
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# default-agent
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [bluetooth]# quit
Mar 12 16:48:40 volumio-studio volumiobt[1943]: [59B blob data]
Mar 12 16:48:43 volumio-studio sudo[2000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 12 16:48:43 volumio-studio sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 16:48:43 volumio-studio sudo[2000]: pam_unix(sudo:session): session closed for user root
Mar 12 16:48:43 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:48:43] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006]
Mar 12 16:48:44 volumio-studio dbus-daemon[502]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30002ms)
Mar 12 16:48:44 volumio-studio dbus-daemon[502]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30001ms)
Mar 12 16:48:46 volumio-studio volumio[829]: error: Failed to retrieve User Session from TIDAL: undefined Error: socket hang up
Mar 12 16:48:46 volumio-studio volumio[829]: error: Could not start login into TIDAL at startup: Error: An error occurred while getting subscription info from Tidal.
Mar 12 16:48:46 volumio-studio volumio[829]: info: Stopping AccessToken refresher cron
Mar 12 16:48:46 volumio-studio volumio[829]: info: AccessToken refresher cron started
Mar 12 16:48:46 volumio-studio volumio[829]: info: Adding TIDAL REST API Endpoints
Mar 12 16:48:46 volumio-studio volumio[829]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Mar 12 16:48:46 volumio-studio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 12 16:48:46 volumio-studio volumio[829]: Error: write EPIPE
Mar 12 16:48:46 volumio-studio volumio[829]: at afterWriteDispatched (internal/stream_base_commons.js:156:25)
Mar 12 16:48:46 volumio-studio volumio[829]: at writeGeneric (internal/stream_base_commons.js:147:3)
Mar 12 16:48:46 volumio-studio volumio[829]: at Socket._writeGeneric (net.js:785:11)
Mar 12 16:48:46 volumio-studio volumio[829]: at Socket._write (net.js:797:8)
Mar 12 16:48:46 volumio-studio volumio[829]: at writeOrBuffer (internal/streams/writable.js:358:12)
Mar 12 16:48:46 volumio-studio volumio[829]: at Socket.Writable.write (internal/streams/writable.js:303:10)
Mar 12 16:48:46 volumio-studio volumio[829]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/handshake.js:82:16
Mar 12 16:48:46 volumio-studio volumio[829]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-native/lib/readline.js:12:11)
Mar 12 16:48:46 volumio-studio volumio[829]: at Socket.emit (events.js:315:20)
Mar 12 16:48:46 volumio-studio volumio[829]: at emitReadable_ (internal/streams/readable.js:569:12)
Mar 12 16:48:46 volumio-studio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:79:21) {
Mar 12 16:48:46 volumio-studio volumio[829]: errno: -32,
Mar 12 16:48:46 volumio-studio volumio[829]: code: 'EPIPE',
Mar 12 16:48:46 volumio-studio volumio[829]: syscall: 'write'
Mar 12 16:48:46 volumio-studio volumio[829]: }
Mar 12 16:48:47 volumio-studio volumio[829]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 12 16:48:48 volumio-studio volumio-remote-updater[507]: [2025-03-12 16:48:48] [connect] Successful connection
Mar 12 16:48:53 volumio-studio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 16:47
Mar 12 16:48:53 volumio-studio sudo[2029]: 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="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET"
VOLUMIO_VERSION="3.785"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"