-- Logs begin at Sun 2025-07-06 04:52:39 UTC, end at Sun 2025-07-06 04:55:05 UTC. --
Jul 06 04:54:03 volumio systemd[1]: firststart.service: Succeeded.
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "airplay_emulation"...
Jul 06 04:54:03 volumio volumio[877]: info: Starting Shairport Sync
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "last_100"...
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "webradio"...
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "i2s_dacs"...
Jul 06 04:54:03 volumio volumio[877]: info: I2S DAC not set, start Auto-detection
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "volumiodiscovery"...
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jul 06 04:54:03 volumio node[877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 06 04:54:03 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** For more information see 
Jul 06 04:54:03 volumio node[877]: *** WARNING *** For more information see 
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jul 06 04:54:03 volumio node[877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 06 04:54:03 volumio node[877]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jul 06 04:54:03 volumio volumio[877]: *** WARNING *** For more information see 
Jul 06 04:54:03 volumio node[877]: *** WARNING *** For more information see 
Jul 06 04:54:03 volumio volumio[877]: info: Discovery: Started advertising with name: Volumio
Jul 06 04:54:03 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "outputs"...
Jul 06 04:54:03 volumio volumio[877]: info: Loading plugin "albumart"...
Jul 06 04:54:04 volumio volumio[877]: info: Plugin example_plugin is not enabled
Jul 06 04:54:04 volumio volumio[877]: info: Loading plugin "inputs"...
Jul 06 04:54:04 volumio volumio[877]: info: Loading plugin "updater_comm"...
Jul 06 04:54:04 volumio volumio-remote-updater[634]: [2025-07-06 04:54:04] [connect] Successful connection
Jul 06 04:54:05 volumio volumio[877]: Forking 1 albumart workers
Jul 06 04:54:06 volumio volumio[877]: Starting albumart workers
Jul 06 04:54:08 volumio volumio[877]: info: Plugin mpdemulation is not enabled
Jul 06 04:54:08 volumio volumio[877]: info: Loading plugin "rest_api"...
Jul 06 04:54:08 volumio volumio[877]: info: Loading plugin "websocket"...
Jul 06 04:54:08 volumio volumio[877]: info: Starting Socket.io Server version 2.3.0
Jul 06 04:54:08 volumio volumio[877]: info: Loading i18n strings for locale en
Jul 06 04:54:08 volumio volumio[877]: Updating browse sources language
Jul 06 04:54:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::initPlayerControls
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: Express server listening on port 3000
Jul 06 04:54:09 volumio volumio[877]:  [Metrics]  WebUI:  37s 547.25ms 
Jul 06 04:54:09 volumio volumio[877]: info: Setting Device type: x86
Jul 06 04:54:09 volumio volumio[877]: info: CoreStateMachine::resetVolumioState
Jul 06 04:54:09 volumio volumio[877]: info: CoreStateMachine::getcurrentVolume
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jul 06 04:54:09 volumio volumio[877]: info: CoreStateMachine::getcurrentVolume
Jul 06 04:54:09 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 04:54:10 volumio sudo[1481]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 04:54:10 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:10 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:10 volumio volumio[877]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Jul 06 04:54:10 volumio volumio[877]: info: Completed loading Core Plugins
Jul 06 04:54:10 volumio volumio[877]: info: Preparing to generate the ALSA configuration file
Jul 06 04:54:10 volumio sudo[1484]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 04:54:10 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:10 volumio volumio[877]: info: Cannot read play queue from file
Jul 06 04:54:10 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:10 volumio volumio[877]: info: Volumio Network Manager: Network status updated: 1
Jul 06 04:54:10 volumio volumio[877]: Unable to parse:
Jul 06 04:54:10 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:10 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:10 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:10 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:10 volumio volumio[877]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jul 06 04:54:10 volumio volumio[877]: Unable to parse:
Jul 06 04:54:10 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:10 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:10 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:10 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:10 volumio volumio[877]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jul 06 04:54:10 volumio volumio[877]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Jul 06 04:54:10 volumio volumio[877]: wlan0     Interface doesn't support scanning.
Jul 06 04:54:10 volumio volumio[877]: info: Cannot use regular scanning, forcing with ap-force
Jul 06 04:54:10 volumio sudo[1491]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Jul 06 04:54:10 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:10 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:10 volumio volumio[877]: command failed: No such device (-19)
Jul 06 04:54:10 volumio volumio[877]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Jul 06 04:54:10 volumio volumio[877]: command failed: No such device (-19)
Jul 06 04:54:10 volumio volumio[877]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 1
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 2
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 3
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 6
Jul 06 04:54:10 volumio volumio[877]: Unable to parse:
Jul 06 04:54:10 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:10 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:10 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:10 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:10 volumio volumio[877]: info: VolumeController:: Volume=undefined Mute =false
Jul 06 04:54:10 volumio volumio[877]: info: CoreStateMachine::pushState
Jul 06 04:54:10 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:10 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 04:54:10 volumio volumio[877]: info: CoreCommandRouter::volumioPushState
Jul 06 04:54:10 volumio volumio[877]: info: CoreStateMachine::updateTrackBlock
Jul 06 04:54:10 volumio volumio[877]: info: CorePlayQueue::getTrackBlock
Jul 06 04:54:10 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 04:54:10 volumio volumio[877]: Unable to parse:
Jul 06 04:54:10 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:10 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:10 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:10 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:10 volumio volumio[877]: info: VolumeController:: Volume=undefined Mute =false
Jul 06 04:54:10 volumio volumio[877]: info: CoreStateMachine::pushState
Jul 06 04:54:10 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:10 volumio volumio[877]: info: CoreCommandRouter::volumioPushState
Jul 06 04:54:10 volumio volumio[877]: info: CoreStateMachine::updateTrackBlock
Jul 06 04:54:10 volumio volumio[877]: info: CorePlayQueue::getTrackBlock
Jul 06 04:54:10 volumio volumio[877]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 04:54:10 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 7
Jul 06 04:54:10 volumio volumio[877]: Unable to parse:
Jul 06 04:54:10 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:10 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:10 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:10 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:10 volumio volumio[877]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jul 06 04:54:10 volumio volumio[877]: info: Asound.conf file written
Jul 06 04:54:11 volumio sudo[1504]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jul 06 04:54:11 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:11 volumio volumio[877]: No state is present for card Generic
Jul 06 04:54:11 volumio volumio[877]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100200" "0x1002" "0x1314"
Jul 06 04:54:11 volumio volumio[877]: Hardware is initialized using a generic method
Jul 06 04:54:11 volumio volumio[877]: No state is present for card Generic
Jul 06 04:54:11 volumio volumio[877]: No state is present for card II
Jul 06 04:54:11 volumio volumio[877]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" ""
Jul 06 04:54:11 volumio volumio[877]: Hardware is initialized using a generic method
Jul 06 04:54:11 volumio volumio[877]: No state is present for card II
Jul 06 04:54:11 volumio volumio[877]: info: Output device has changed, restarting MPD
Jul 06 04:54:11 volumio volumio[877]: info: Output device has changed, restarting Shairport Sync
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:11 volumio sudo[1509]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 04:54:11 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:11 volumio sudo[1511]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 04:54:11 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio systemd[1]: Stopping Music Player Daemon...
Jul 06 04:54:11 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:11 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 04:54:11 volumio volumio[877]: info: ___________ START PLUGINS ___________
Jul 06 04:54:11 volumio volumio[877]: info: Setting Hotspot Unique name on first start: Volumio-7CB9A
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::Reload Ui
Jul 06 04:54:11 volumio volumio[877]: info: ControllerMpd::onStart: Initializing MPD
Jul 06 04:54:11 volumio volumio[877]: info: Creating MPD Configuration file
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 06 04:54:11 volumio volumio[877]: info: [1751777651361] CoreMusicLibrary::Adding element Media Servers
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:11 volumio sudo[1518]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 04:54:11 volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio sudo[1520]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 04:54:11 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio sudo[1518]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 06 04:54:11 volumio volumio[877]: info: [1751777651644] CoreMusicLibrary::Adding element Last_100
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jul 06 04:54:11 volumio volumio[877]: info: [1751777651650] CoreMusicLibrary::Adding element Webradio
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 04:54:11 volumio volumio[877]: info: Initializing BBC Radios
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 04:54:11 volumio systemd[1]: mpd.service: Succeeded.
Jul 06 04:54:11 volumio systemd[1]: Stopped Music Player Daemon.
Jul 06 04:54:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:11 volumio volumio[877]: info: Volumio Calling Home
Jul 06 04:54:11 volumio systemd[1]: Starting Music Player Daemon...
Jul 06 04:54:11 volumio sudo[1539]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jul 06 04:54:11 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:11 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:12 volumio volumio[877]: Unable to parse:
Jul 06 04:54:12 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:12 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:12 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:12 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:12 volumio volumio[877]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Jul 06 04:54:12 volumio volumio[877]: info: MPD Permissions set
Jul 06 04:54:12 volumio volumio[877]: info: MPD Permissions set
Jul 06 04:54:12 volumio volumio[877]: Unable to parse:
Jul 06 04:54:12 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:12 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:12 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:12 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:12 volumio volumio[877]: info: VolumeController:: Volume=undefined Mute =false
Jul 06 04:54:12 volumio volumio[877]: info: CoreStateMachine::pushState
Jul 06 04:54:12 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::volumioPushState
Jul 06 04:54:12 volumio sudo[1590]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf
Jul 06 04:54:12 volumio sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:12 volumio volumio[877]: Unable to parse:
Jul 06 04:54:12 volumio volumio[877]:  Simple mixer control 'IEC958',0
Jul 06 04:54:12 volumio volumio[877]:   Capabilities: pswitch pswitch-joined
Jul 06 04:54:12 volumio volumio[877]:   Playback channels: Mono
Jul 06 04:54:12 volumio volumio[877]:   Mono: Playback [off]
Jul 06 04:54:12 volumio volumio[877]: info: VolumeController:: Volume=undefined Mute =false
Jul 06 04:54:12 volumio volumio[877]: info: CoreStateMachine::pushState
Jul 06 04:54:12 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::volumioPushState
Jul 06 04:54:12 volumio sudo[1597]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jul 06 04:54:12 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jul 06 04:54:12 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:12 volumio sudo[1590]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:12 volumio volumio[877]: error: error
Jul 06 04:54:12 volumio volumio[877]: error: error
Jul 06 04:54:12 volumio volumio[877]: error: error
Jul 06 04:54:12 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jul 06 04:54:12 volumio volumio[877]: info: Volumio called home
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:12 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jul 06 04:54:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:13 volumio volumio[877]: info: Discovery: adding 33dcebae-7b3d-4b75-9d36-965081659a96
Jul 06 04:54:13 volumio volumio[877]: info: Discovery: Found device Volumio
Jul 06 04:54:13 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:13 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:13 volumio volumio[877]: info: Permissions for /etc/hostapd/hostapd.conf
Jul 06 04:54:13 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Jul 06 04:54:13 volumio volumio[877]: info: Discovery: Restarting Advertising due to device name change
Jul 06 04:54:13 volumio volumio[877]: info: Upmpdcli Daemon Started
Jul 06 04:54:13 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:13 volumio sudo[1615]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Jul 06 04:54:13 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:13 volumio systemd[1]: Starting Wireless Services...
Jul 06 04:54:13 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:13 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:13 volumio volumio[877]: info: Starting Shairport Sync
Jul 06 04:54:14 volumio volumio[877]: info: Starting Shairport Sync
Jul 06 04:54:14 volumio wireless.js[1617]: WIRELESS: No wireless interface, exiting
Jul 06 04:54:14 volumio sudo[1630]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 06 04:54:14 volumio iw[1629]: command failed: No such device (-19)
Jul 06 04:54:14 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:14 volumio volumio[877]: info: Starting Shairport Sync
Jul 06 04:54:14 volumio iwconfig[1633]: Error for wireless request "Set Power Management" (8B2C) :
Jul 06 04:54:14 volumio iwconfig[1633]:     SET failed on device wlan0 ; No such device.
Jul 06 04:54:14 volumio systemd[1]: wireless.service: Succeeded.
Jul 06 04:54:14 volumio systemd[1]: Started Wireless Services.
Jul 06 04:54:14 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 06 04:54:14 volumio systemd[1]: shairport-sync.service: Succeeded.
Jul 06 04:54:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio sudo[1637]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 06 04:54:14 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio sudo[1640]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jul 06 04:54:14 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 06 04:54:14 volumio systemd[1]: shairport-sync.service: Succeeded.
Jul 06 04:54:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio mpd[1546]: Jul 06 04:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jul 06 04:54:14 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio systemd[1]: Started Music Player Daemon.
Jul 06 04:54:14 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jul 06 04:54:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Jul 06 04:54:14 volumio systemd[1]: shairport-sync.service: Succeeded.
Jul 06 04:54:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio volumio[877]: info: Shairport-Sync Started
Jul 06 04:54:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jul 06 04:54:14 volumio volumio[877]: Error adding Membership: Error: addMembership EINVAL
Jul 06 04:54:14 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:14 volumio volumio[877]: info: Discovery: A device disappeared from network
Jul 06 04:54:14 volumio volumio[877]: info: Discovery: Device volumio disappeared from network
Jul 06 04:54:14 volumio volumio[877]: info: Completed starting Core Plugins
Jul 06 04:54:14 volumio volumio[877]: info: -------------------------------------------
Jul 06 04:54:14 volumio volumio[877]: info: -----    MyVolumio plugins startup     ----
Jul 06 04:54:14 volumio volumio[877]: info: -------------------------------------------
Jul 06 04:54:14 volumio volumio[877]: info: [MyVolumio PluginManager] Fetching plans data....
Jul 06 04:54:14 volumio volumio[877]: info: Shairport-Sync Started
Jul 06 04:54:14 volumio volumio[877]: info: MPD running with PID1546
Jul 06 04:54:14 volumio volumio[877]:  ,establishing connection
Jul 06 04:54:14 volumio volumio[877]: info: Shairport-Sync Started
Jul 06 04:54:14 volumio volumio[877]: error: MPD error: The expression evaluated to a falsy value:
Jul 06 04:54:14 volumio volumio[877]:   assert.ok(self.idling)
Jul 06 04:54:14 volumio volumio[877]: error: The expression evaluated to a falsy value:
Jul 06 04:54:14 volumio volumio[877]:   assert.ok(self.idling)
Jul 06 04:54:14 volumio volumio[877]: error: MPD error: The expression evaluated to a falsy value:
Jul 06 04:54:14 volumio volumio[877]:   assert.ok(self.idling)
Jul 06 04:54:14 volumio volumio[877]: error: The expression evaluated to a falsy value:
Jul 06 04:54:14 volumio volumio[877]:   assert.ok(self.idling)
Jul 06 04:54:14 volumio volumio[877]: error: updateQueue error: null
Jul 06 04:54:15 volumio volumio[1599]: Generating RSA private key, 4096 bit long modulus (2 primes)
Jul 06 04:54:15 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 9
Jul 06 04:54:15 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 04:54:15 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:15 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:16 volumio volumio[1599]: ...................................++++
Jul 06 04:54:18 volumio volumio[877]: info: Discovery: Started advertising with name: Volumio
Jul 06 04:54:18 volumio volumio[1599]: ...........................................................................................++++
Jul 06 04:54:18 volumio volumio[1599]: e is 65537 (0x010001)
Jul 06 04:54:18 volumio volumio[1599]: writing RSA key
Jul 06 04:54:19 volumio volumio[877]: info: Discovery: adding 33dcebae-7b3d-4b75-9d36-965081659a96
Jul 06 04:54:19 volumio volumio[877]: info: Discovery: Found device Volumio
Jul 06 04:54:19 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:19 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Jul 06 04:54:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Jul 06 04:54:19 volumio sudo[1713]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 04:54:19 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:19 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:19 volumio volumio[877]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Jul 06 04:54:19 volumio volumio-remote-updater[634]: [2025-07-06 04:54:19] [connect] Successful connection
Jul 06 04:54:19 volumio volumio-remote-updater[634]: [2025-07-06 04:54:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751777659 101
Jul 06 04:54:19 volumio volumio[877]: 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: 10
Jul 06 04:54:21 volumio dbus-daemon[638]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.15' (uid=0 pid=1406 comm="/usr/lib/chromium/chromium --show-component-extens")
Jul 06 04:54:21 volumio systemd[1]: Starting Bluetooth service...
Jul 06 04:54:22 volumio kernel: cryptd: max_cpu_qlen set to 1000
Jul 06 04:54:22 volumio bluetoothd[1727]: Bluetooth daemon 5.50
Jul 06 04:54:22 volumio kernel: Bluetooth: Core ver 2.22
Jul 06 04:54:22 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
Jul 06 04:54:22 volumio kernel: Bluetooth: HCI device and connection manager initialized
Jul 06 04:54:22 volumio kernel: Bluetooth: HCI socket layer initialized
Jul 06 04:54:22 volumio kernel: Bluetooth: L2CAP socket layer initialized
Jul 06 04:54:22 volumio kernel: Bluetooth: SCO socket layer initialized
Jul 06 04:54:22 volumio bluetoothd[1727]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Jul 06 04:54:22 volumio dbus-daemon[638]: [system] Successfully activated service 'org.bluez'
Jul 06 04:54:22 volumio systemd[1]: Started Bluetooth service.
Jul 06 04:54:22 volumio bluetoothd[1727]: Starting SDP server
Jul 06 04:54:22 volumio bluetoothd[1727]: Excluding (cli) sap
Jul 06 04:54:22 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jul 06 04:54:22 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Jul 06 04:54:22 volumio kernel: Bluetooth: BNEP socket layer initialized
Jul 06 04:54:22 volumio dbus-daemon[638]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.16' (uid=0 pid=1727 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Jul 06 04:54:22 volumio bluetoothd[1727]: Bluetooth management interface 1.22 initialized
Jul 06 04:54:22 volumio systemd[1]: Starting Hostname Service...
Jul 06 04:54:23 volumio dbus-daemon[638]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jul 06 04:54:23 volumio systemd[1]: Started Hostname Service.
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin bluetooth to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin multiroom to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin metavolumio to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin cd_controller to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin qobuzconnect to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin smart_inputs to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: Adding plugin tidalconnect to MyMusic Plugins
Jul 06 04:54:23 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jul 06 04:54:31 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jul 06 04:54:31 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jul 06 04:54:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:31 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:31 volumio volumio[877]: info: Starting MyVolumio Remote Streaming Endpoints
Jul 06 04:54:31 volumio volumio[877]: info: MyVolumio not started
Jul 06 04:54:31 volumio volumio[877]: info: Initializing device activation check
Jul 06 04:54:31 volumio volumio[877]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jul 06 04:54:31 volumio volumio[877]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jul 06 04:54:32 volumio volumio[877]: info: Starting Streaming Service Transparent Proxy
Jul 06 04:54:33 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jul 06 04:54:33 volumio volumio[877]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jul 06 04:54:33 volumio volumio[877]: info: Streaming services startup
Jul 06 04:54:33 volumio volumio[877]: info: Starting Streaming Daemon
Jul 06 04:54:33 volumio sudo[1872]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jul 06 04:54:33 volumio sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:33 volumio sudo[1872]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:33 volumio volumio[877]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jul 06 04:54:33 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Jul 06 04:54:33 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Jul 06 04:54:33 volumio volumio[877]: info: Refreshing Cached IP Addresses
Jul 06 04:54:33 volumio sudo[1880]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Jul 06 04:54:33 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:33 volumio sudo[1882]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 04:54:33 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:33 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:33 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:33 volumio sudo[1885]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 04:54:33 volumio sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:33 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon
Jul 06 04:54:33 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jul 06 04:54:33 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jul 06 04:54:33 volumio sudo[1885]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:33 volumio systemd[1]: upmpdcli.service: Succeeded.
Jul 06 04:54:34 volumio volumio[877]: STREAMING PROXY: Starting server on port 3245
Jul 06 04:54:34 volumio volumio[877]: Node JS runtime: 14
Jul 06 04:54:35 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jul 06 04:54:36 volumio volumio[877]: info: Received Get System Info
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 06 04:54:36 volumio volumio[877]: info: Discovery: Getting this device information
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:36 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:36 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 06 04:54:36 volumio volumio-remote-updater[634]: No test mode
Jul 06 04:54:36 volumio volumio-remote-updater[634]: No alpha test mode
Jul 06 04:54:37 volumio volumio[877]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nDisable UI controls during DLNA playback
\nFix for Cast functionality halting due to memory filling up
\nFix for XML parsing on web-radio search
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.819","updateavailable":true}
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:37 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 04:54:37 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:37 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:37 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:37 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:37 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:37 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:37 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:37 volumio volumio[877]: info: Got it
Jul 06 04:54:37 volumio volumio[877]: info: Got it
Jul 06 04:54:37 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:37 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:37 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:38 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 06 04:54:38 volumio volumio[877]: info: Loading i18n strings for locale en
Jul 06 04:54:38 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 06 04:54:38 volumio volumio[877]: info: Setting timezone to UTC
Jul 06 04:54:38 volumio sudo[1899]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 06 04:54:38 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:38 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:38 volumio sudo[1902]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jul 06 04:54:38 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:38 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:38 volumio sudo[1905]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 06 04:54:38 volumio sudo[1905]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:38 volumio sudo[1905]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:38 volumio sudo[1908]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jul 06 04:54:38 volumio sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:38 volumio dbus-daemon[638]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=1909 comm="/usr/bin/timedatectl set-timezone UTC ")
Jul 06 04:54:38 volumio systemd[1]: Starting Time & Date Service...
Jul 06 04:54:38 volumio dbus-daemon[638]: [system] Successfully activated service 'org.freedesktop.timedate1'
Jul 06 04:54:38 volumio systemd[1]: Started Time & Date Service.
Jul 06 04:54:38 volumio sudo[1908]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:38 volumio volumio[877]: info: Fetching Streaming Services browse cache
Jul 06 04:54:38 volumio volumio[877]: Updating browse sources language
Jul 06 04:54:38 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 04:54:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:41 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 06 04:54:42 volumio volumio-remote-updater[634]: No test mode
Jul 06 04:54:42 volumio volumio-remote-updater[634]: No alpha test mode
Jul 06 04:54:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jul 06 04:54:42 volumio volumio[877]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nDisable UI controls during DLNA playback
\nFix for Cast functionality halting due to memory filling up
\nFix for XML parsing on web-radio search
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.819","updateavailable":true}
Jul 06 04:54:42 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 06 04:54:43 volumio sudo[2100]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 04:54:43 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:43 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:43 volumio sudo[2103]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 04:54:43 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:43 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:43 volumio sudo[2107]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jul 06 04:54:43 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:43 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Jul 06 04:54:43 volumio sudo[2107]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:43 volumio volumio[877]: info: Upmpdcli Daemon Started
Jul 06 04:54:43 volumio volumio[2110]: writing RSA key
Jul 06 04:54:46 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 06 04:54:46 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Jul 06 04:54:46 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:46 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:50 volumio kernel: logitech-hidpp-device 0003:046D:4055.0006: HID++ 4.5 device connected.
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 06 04:54:51 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 04:54:53 volumio volumio[877]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jul 06 04:54:53 volumio volumio[877]: info: Completed starting MyVolumio Plugin
Jul 06 04:54:53 volumio volumio[877]:  [Metrics]  CommandRouter:  79s 247.87ms 
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::volumiosetStartupVolume
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent
Jul 06 04:54:53 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:53 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:53 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Got it
Jul 06 04:54:53 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:53 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:53 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Got it
Jul 06 04:54:53 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:53 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:53 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:53 volumio volumio[877]: info: Got it
Jul 06 04:54:53 volumio volumio[877]: info: Got it
Jul 06 04:54:53 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:53 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:53 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:53 volumio kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj.
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 06 04:54:54 volumio volumio[877]: info: Version has changed, forcing UI Reload
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::Reload Ui
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:54 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:54 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:54 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:54 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:54 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:54 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:54 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:54 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:54 volumio volumio[877]: info: Got it
Jul 06 04:54:54 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:54 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 06 04:54:54 volumio volumio[877]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 06 04:54:54 volumio volumio[877]: info: Received Get System Version
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 04:54:54 volumio volumio[877]: info: Received Get System Info
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 06 04:54:54 volumio volumio[877]: info: Discovery: Getting this device information
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:54 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:54 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 06 04:54:55 volumio sudo[2166]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 04:54:55 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:55 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:55 volumio sudo[2168]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 04:54:55 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:55 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:55 volumio volumio[877]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jul 06 04:54:56 volumio volumio[877]: info: Received Get System Info
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 06 04:54:56 volumio volumio[877]: info: Discovery: Getting this device information
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:56 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 06 04:54:56 volumio volumio-remote-updater[634]: No test mode
Jul 06 04:54:56 volumio volumio-remote-updater[634]: No alpha test mode
Jul 06 04:54:56 volumio volumio[877]: verbose: New Socket.io Connection to 10.1.2.7 from 10.1.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Jul 06 04:54:56 volumio volumio[877]: info: Received Get System Info
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 06 04:54:56 volumio volumio[877]: info: Discovery: Getting this device information
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::volumioGetState
Jul 06 04:54:56 volumio volumio[877]: info: CorePlayQueue::getTrack 0
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:56 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:56 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:56 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:56 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:56 volumio volumio[877]: info: Got it
Jul 06 04:54:56 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:56 volumio volumio-remote-updater[634]: No test mode
Jul 06 04:54:56 volumio volumio-remote-updater[634]: No alpha test mode
Jul 06 04:54:56 volumio volumio[877]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nDisable UI controls during DLNA playback
\nFix for Cast functionality halting due to memory filling up
\nFix for XML parsing on web-radio search
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.819","updateavailable":true}
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 06 04:54:56 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:56 volumio volumio[877]: info: Retrieving Cloud Streaming UI
Jul 06 04:54:57 volumio volumio[877]: info: Getting Tidal Cloud Configuration
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:57 volumio volumio[877]: info: Getting Qobuz Cloud Configuration
Jul 06 04:54:57 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:57 volumio volumio[877]: info: Getting Spotify Cloud Configuration
Jul 06 04:54:57 volumio volumio[877]: info: Asking plugin for UI Config
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:57 volumio volumio[877]: info: Saving Spotify Acccount
Jul 06 04:54:57 volumio volumio[877]: info: Got Tidal Cloud Configuration
Jul 06 04:54:57 volumio volumio[877]: info: Got it
Jul 06 04:54:57 volumio volumio[877]: info: Got it
Jul 06 04:54:57 volumio volumio[877]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 04:54:57 volumio volumio[877]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 06 04:54:57 volumio volumio[877]: info: Loading i18n strings for locale en
Jul 06 04:54:57 volumio volumio[877]: Updating browse sources language
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:57 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 06 04:54:57 volumio volumio[877]: info: Setting timezone to UTC
Jul 06 04:54:57 volumio sudo[2182]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 06 04:54:57 volumio sudo[2182]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:57 volumio sudo[2182]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:58 volumio sudo[2185]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jul 06 04:54:58 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:58 volumio sudo[2185]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:58 volumio sudo[2188]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 06 04:54:58 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:58 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:58 volumio sudo[2191]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jul 06 04:54:58 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:58 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:58 volumio volumio[877]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nDisable UI controls during DLNA playback
\nFix for Cast functionality halting due to memory filling up
\nFix for XML parsing on web-radio search
\n
\nNEW ADDITIONS
\n\nAdd link for downloading CORRD in the side-menu
\n\n","title":"Update v3.819","updateavailable":true}
Jul 06 04:54:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jul 06 04:54:58 volumio volumio[877]: info: Fetching Streaming Services browse cache
Jul 06 04:54:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 06 04:54:58 volumio volumio[877]: info: Loading i18n strings for locale en
Jul 06 04:54:58 volumio volumio[877]: Updating browse sources language
Jul 06 04:54:58 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:54:59 volumio volumio[877]: info: Fetching Streaming Services browse cache
Jul 06 04:54:59 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 06 04:54:59 volumio volumio[877]: info: Setting timezone to Asia/Jakarta
Jul 06 04:54:59 volumio sudo[2208]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 06 04:54:59 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:59 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:59 volumio sudo[2211]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Jakarta /etc/localtime
Jul 06 04:54:59 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:59 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:59 volumio sudo[2214]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 06 04:54:59 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:59 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:59 volumio sudo[2217]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Jakarta
Jul 06 04:54:59 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:54:59 volumio systemd-timedated[1910]: Failed to set time zone: Permission denied
Jul 06 04:54:59 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root
Jul 06 04:54:59 volumio volumio[877]: Failed to set time zone: Access denied
Jul 06 04:54:59 volumio volumio[877]: info: Could not set timezone, retrying
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Jul 06 04:55:00 volumio volumio[877]: info: Loading i18n strings for locale en
Jul 06 04:55:00 volumio volumio[877]: Updating browse sources language
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 04:55:00 volumio volumio[877]: info: BOOT COMPLETED
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Jul 06 04:55:00 volumio volumio[877]: info: Setting timezone to UTC
Jul 06 04:55:00 volumio sudo[2220]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Jul 06 04:55:00 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio sudo[2223]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime
Jul 06 04:55:00 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio sudo[2226]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Jul 06 04:55:00 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio sudo[2229]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC
Jul 06 04:55:00 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio systemd-timedated[1910]: Failed to set time zone: Permission denied
Jul 06 04:55:00 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio volumio[877]: Failed to set time zone: Access denied
Jul 06 04:55:00 volumio volumio[877]: info: Could not set timezone, retrying
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Jul 06 04:55:00 volumio sudo[2232]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jul 06 04:55:00 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio volumio[877]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Jul 06 04:55:00 volumio volumio[877]: wlan0     Interface doesn't support scanning.
Jul 06 04:55:00 volumio volumio[877]: info: Cannot use regular scanning, forcing with ap-force
Jul 06 04:55:00 volumio sudo[2237]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Jul 06 04:55:00 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:00 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:00 volumio volumio[877]: command failed: No such device (-19)
Jul 06 04:55:00 volumio volumio[877]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Jul 06 04:55:00 volumio volumio[877]: command failed: No such device (-19)
Jul 06 04:55:00 volumio volumio[877]: info: Fetching Streaming Services browse cache
Jul 06 04:55:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Jul 06 04:55:05 volumio volumio[877]: info: MyVolumio login type: Token
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage
Jul 06 04:55:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Jul 06 04:55:05 volumio sudo[2248]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Jakarta
Jul 06 04:55:05 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 04:55:05 volumio systemd-timedated[1910]: Failed to set time zone: Permission denied
Jul 06 04:55:05 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root
Jul 06 04:55:05 volumio volumio[877]: Failed to set time zone: Access denied
Jul 06 04:55:05 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 06 04:55:05 volumio volumio[877]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Asia/Jakarta'
Jul 06 04:55:05 volumio volumio[877]: Failed to set time zone: Access denied
Jul 06 04:55:05 volumio volumio[877]:     at checkExecSyncError (child_process.js:790:11)
Jul 06 04:55:05 volumio volumio[877]:     at execSync (child_process.js:863:15)
Jul 06 04:55:05 volumio volumio[877]:     at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9)
Jul 06 04:55:05 volumio volumio[877]:     at listOnTimeout (internal/timers.js:557:17)
Jul 06 04:55:05 volumio volumio[877]:     at processTimers (internal/timers.js:500:7) {
Jul 06 04:55:05 volumio volumio[877]:   status: 1,
Jul 06 04:55:05 volumio volumio[877]:   signal: null,
Jul 06 04:55:05 volumio volumio[877]:   output: [ null, '', 'Failed to set time zone: Access denied\n' ],
Jul 06 04:55:05 volumio volumio[877]:   pid: 2247,
Jul 06 04:55:05 volumio volumio[877]:   stdout: '',
Jul 06 04:55:05 volumio volumio[877]:   stderr: 'Failed to set time zone: Access denied\n'
Jul 06 04:55:05 volumio volumio[877]: }
Jul 06 04:55:05 volumio volumio[877]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 06 04:55:05 volumio sudo[2259]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-06 04:54
Jul 06 04:55:05 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST"
VOLUMIO_VERSION="3.816"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"