-- Logs begin at Fri 2026-10-16 19:58:14 UTC, end at Fri 2026-10-16 20:00:03 UTC. -- Oct 16 19:59:02 volumio systemd[1]: Time has been changed Oct 16 19:59:04 volumio volumio-remote-updater[602]: [2026-10-16 19:59:04] [connect] Successful connection Oct 16 19:59:06 volumio volumio[1038]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 16 19:59:06 volumio volumio[1038]: info: CoreCommandRouter::Close All Modals sent Oct 16 19:59:06 volumio volumio[1038]: info: CoreCommandRouter::Close All Modals sent Oct 16 19:59:06 volumio volumio[1038]: Express server listening on port 3000 Oct 16 19:59:06 volumio volumio[1038]: [Metrics] WebUI: 12s 112.01ms Oct 16 19:59:06 volumio volumio[1038]: Volumio Calling Home Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::resetVolumioState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getcurrentVolume Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::updateTrackBlock Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrackBlock Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 19:59:07 volumio volumio[1038]: info: Setting Device type: Raspberry PI Oct 16 19:59:07 volumio volumio[1038]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Oct 16 19:59:07 volumio volumio[1038]: info: MPD running with PID675 Oct 16 19:59:07 volumio volumio[1038]: ,establishing connection Oct 16 19:59:07 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 16 19:59:07 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:07 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:07 volumio volumio-remote-updater[602]: [2026-10-16 19:59:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1792180744 101 Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:07 volumio volumio[1038]: info: Oct 16 19:59:07 volumio volumio[1038]: ---------------------------- Client requests Volume 50 Oct 16 19:59:07 volumio volumio[1038]: info: VolumeController::SetAlsaVolume50 Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:59:07 volumio volumio[1038]: info: Reloading queue from file Oct 16 19:59:07 volumio volumio[1038]: info: null Oct 16 19:59:07 volumio volumio[1038]: info: Setting volume on startup at 50 Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::setRepeat true single undefined Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::setRandom null Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:07 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:07 volumio volumio[1038]: info: Starting Shairport Sync Oct 16 19:59:07 volumio volumio[1038]: info: Starting Shairport Sync Oct 16 19:59:07 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 19:59:07 volumio sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 19:59:07 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:07 volumio sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:07 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 16 19:59:07 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 19:59:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 16 19:59:07 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 16 19:59:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 19:59:07 volumio sudo[1194]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:07 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 16 19:59:08 volumio volumio[1038]: info: ir_controller: LIRC correctly updated. Oct 16 19:59:08 volumio volumio[1038]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Oct 16 19:59:08 volumio volumio[1038]: Discovery: adding d3baae3b-1a70-4884-b247-f96b919a671a Oct 16 19:59:08 volumio volumio[1038]: info: mDNS: Found device Volumio Oct 16 19:59:08 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 19:59:08 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:08 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:08 volumio volumio[1038]: info: Shairport-Sync Started Oct 16 19:59:08 volumio volumio[1038]: Error adding Membership: Error: addMembership EINVAL Oct 16 19:59:08 volumio volumio[1038]: info: Shairport-Sync Started Oct 16 19:59:08 volumio volumio[1038]: info: ir_controller: Raspberry Pi revision code: a020d3 Oct 16 19:59:08 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 19:59:08 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:08 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 19:59:08 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:08 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio volumio[1038]: info: ControllerAutoPlay - mpd ready Oct 16 19:59:09 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lirc.service Oct 16 19:59:09 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 19:59:09 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:09 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:09 volumio systemd[1]: Stopped LSB: Starts LIRC daemon.. Oct 16 19:59:09 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Oct 16 19:59:09 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio volumio[1038]: info: ir_controller: systemctl stop lirc.service succeeded. Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 19:59:09 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:09 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:09 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 19:59:09 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio volumio[1038]: info: Listing playlists Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Oct 16 19:59:09 volumio volumio[1038]: info: CoreStateMachine::getQueue Oct 16 19:59:09 volumio volumio[1038]: info: CorePlayQueue::getQueue Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 19:59:09 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:59:09 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 16 19:59:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 19:59:09 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Oct 16 19:59:09 volumio sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio sudo[1278]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio kernel: Registered IR keymap rc-rc6-mce Oct 16 19:59:09 volumio kernel: IR RC6 protocol handler initialized Oct 16 19:59:09 volumio volumio[1038]: info: Upmpdcli Daemon Started Oct 16 19:59:09 volumio volumio[1038]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Oct 16 19:59:09 volumio kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Oct 16 19:59:09 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input2 Oct 16 19:59:09 volumio kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Oct 16 19:59:09 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lirc.service Oct 16 19:59:09 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:09 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Oct 16 19:59:09 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:09 volumio lirc[1315]: Loading LIRC modules:. Oct 16 19:59:09 volumio volumio[1038]: error: ir_controller: Failed to stop lirc.service: Error: Command failed: /usr/bin/sudo /bin/systemctl stop lirc.service Oct 16 19:59:09 volumio volumio[1038]: Job for lirc.service canceled. Oct 16 19:59:09 volumio dhcpcd[511]: timed out Oct 16 19:59:09 volumio dhcpcd[511]: exited Oct 16 19:59:09 volumio ifup[478]: dhcpcd[511]: timed out Oct 16 19:59:09 volumio ifup[478]: dhcpcd[511]: exited Oct 16 19:59:09 volumio ifup[478]: Failed to bring up eth0. Oct 16 19:59:09 volumio kernel: input: lircd as /devices/virtual/input/input3 Oct 16 19:59:09 volumio lircd-0.9.0-pre1[1335]: lircd(default) ready, using /var/run/lirc/lircd Oct 16 19:59:09 volumio lirc[1315]: Starting remote control daemon(s) : LIRC :. Oct 16 19:59:09 volumio lircd-0.9.0-pre1[1335]: accepted new client on /var/run/lirc/lircd Oct 16 19:59:09 volumio lirc[1315]: Starting execution daemon: irexec:. Oct 16 19:59:09 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 19:59:10 volumio volumio[1038]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 19:59:10 volumio volumio[1038]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 19:59:10 volumio volumio[1038]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 19:59:10 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 19:59:12 volumio volumio[1038]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 19:59:13 volumio volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 19:59:13 volumio volumio[1038]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 19:59:13 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:59:13 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:59:13 volumio volumio[1038]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 19:59:13 volumio volumio[1038]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 19:59:13 volumio volumio[1038]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 19:59:13 volumio volumio[1038]: info: Streaming services startup Oct 16 19:59:13 volumio volumio[1038]: info: Starting Streaming Daemon Oct 16 19:59:13 volumio volumio[1038]: info: MyVolumio not started Oct 16 19:59:13 volumio volumio[1038]: info: Initializing device activation check Oct 16 19:59:13 volumio sudo[1354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 19:59:13 volumio sudo[1354]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:13 volumio volumio[1038]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 19:59:13 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 16 19:59:13 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 16 19:59:13 volumio sudo[1354]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:13 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 16 19:59:13 volumio volumio-streaming-daemon[1360]: ############################ Oct 16 19:59:13 volumio volumio-streaming-daemon[1360]: # Volumio Streaming Daemon # Oct 16 19:59:13 volumio volumio-streaming-daemon[1360]: # Running on port 7777 # Oct 16 19:59:13 volumio volumio-streaming-daemon[1360]: ############################ Oct 16 19:59:13 volumio volumio-streaming-daemon[1360]: Environment: production Oct 16 19:59:13 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lirc.service Oct 16 19:59:13 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:59:13 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Oct 16 19:59:13 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Oct 16 19:59:13 volumio volumio[1038]: info: ir_controller: systemctl start lirc.service succeeded. Oct 16 19:59:14 volumio volumio[1038]: info: ControllerAutoPlay - getting queue Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getQueue Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getQueue Oct 16 19:59:14 volumio volumio[1038]: info: ControllerAutoPlay - start playing -> queue is not empty Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPlay Oct 16 19:59:14 volumio volumio[1038]: UNSET VOLATILE Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::play index undefined Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::startPlaybackTimer Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:14 volumio volumio[1038]: info: [1792180754119] ControllerWebradio::clearAddPlayTrack Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand stop Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand clear Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Oct 16 19:59:14 volumio volumio[1038]: info: Ignoring MPD Status Update Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand load "http://nashe1.hostingradio.ru:80/jazz-256" Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: null Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 7ms Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand add "http://nashe1.hostingradio.ru:80/jazz-256" Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Oct 16 19:59:14 volumio volumio[1038]: info: Ignoring MPD Status Update Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand play Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 6ms Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces state update: player Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::getState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand status Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand playlistinfo Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Oct 16 19:59:14 volumio volumio[1038]: info: Ignoring MPD Status Update Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces state update: player Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces system playlist update Oct 16 19:59:14 volumio volumio[1038]: info: Ignoring MPD Status Update Oct 16 19:59:14 volumio volumio[1038]: info: Oct 16 19:59:14 volumio volumio[1038]: ---------------------------- MPD announces state update: player Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::getState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand status Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::getState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand status Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseTrackInfo Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:14 volumio volumio[1038]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Magnus Lindgren;Nils Landgren - A Whiter Shade of Pale","artist":"Radio Jazz 89.1 FM","album":null,"uri":"http://nashe1.hostingradio.ru:80/jazz-256","trackType":""} Oct 16 19:59:14 volumio volumio[1038]: info: CURRENT POSITION 0 Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus stop Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 66ms Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 37ms Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 38ms Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand playlistinfo Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseState Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::sendMpdCommand playlistinfo Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: sending command... Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: parsing response... Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseTrackInfo Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::parseTrackInfo Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:14 volumio volumio[1038]: info: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Magnus Lindgren;Nils Landgren - A Whiter Shade of Pale","artist":"Radio Jazz 89.1 FM","album":null,"uri":"http://nashe1.hostingradio.ru:80/jazz-256","trackType":""} Oct 16 19:59:14 volumio volumio[1038]: info: CURRENT POSITION 0 Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Oct 16 19:59:14 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:14 volumio volumio[1038]: info: ControllerMpd::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::servicePushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState Oct 16 19:59:14 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:14 volumio volumio[1038]: info: STATE SERVICE {"status":"play","position":0,"seek":478,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Magnus Lindgren;Nils Landgren - A Whiter Shade of Pale","artist":"Radio Jazz 89.1 FM","album":null,"uri":"http://nashe1.hostingradio.ru:80/jazz-256","trackType":""} Oct 16 19:59:14 volumio volumio[1038]: info: CURRENT POSITION 0 Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState stateService play Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::syncState currentStatus play Oct 16 19:59:14 volumio volumio[1038]: info: Received an update from plugin. extracting info from payload Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:14 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 82ms Oct 16 19:59:14 volumio volumio[1038]: info: ------------------------------ 82ms Oct 16 19:59:16 volumio volumio-streaming-daemon[1360]: Environment: production Oct 16 19:59:16 volumio volumio[1038]: info: Fetching Streaming Services browse cache Oct 16 19:59:16 volumio volumio[1038]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Oct 16 19:59:16 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:59:16 volumio volumio[1038]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Oct 16 19:59:16 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:59:17 volumio volumio[1038]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.773&uuid=8b4da437586d435a3f212bc7af0c9038" http://updates.volumio.org:7070/downloader-v1/track-device Oct 16 19:59:17 volumio volumio[1038]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 19:59:17 volumio volumio[1038]: Dload Upload Total Spent Left Speed Oct 16 19:59:17 volumio volumio[1038]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 16 19:59:17 volumio volumio[1038]: retrying in 5 seconds, trial 0 Oct 16 19:59:17 volumio volumio[1038]: Volumio Calling Home Oct 16 19:59:19 volumio kernel: rc rc0: two consecutive events of type space Oct 16 19:59:20 volumio volumio[1038]: info: Oct 16 19:59:20 volumio volumio[1038]: ---------------------------- Client requests Volume - Oct 16 19:59:20 volumio volumio[1038]: info: VolumeController::SetAlsaVolume- Oct 16 19:59:20 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:20 volumio volumio[1038]: info: AutoStart - getting queue Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::volumioGetQueue Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::getQueue Oct 16 19:59:20 volumio volumio[1038]: info: CorePlayQueue::getQueue Oct 16 19:59:20 volumio volumio[1038]: info: AutoStart - start playing -> queue is not empty Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::volumioPlay Oct 16 19:59:20 volumio volumio[1038]: UNSET VOLATILE Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::play index undefined Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:59:20 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:20 volumio volumio[1038]: info: VolumeController::SetAlsaVolume0 Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:20 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:20 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:20 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:27 volumio volumio[1038]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.773&uuid=8b4da437586d435a3f212bc7af0c9038" http://updates.volumio.org:7070/downloader-v1/track-device Oct 16 19:59:27 volumio volumio[1038]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 19:59:27 volumio volumio[1038]: Dload Upload Total Spent Left Speed Oct 16 19:59:27 volumio volumio[1038]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 16 19:59:27 volumio volumio[1038]: retrying in 5 seconds, trial 1 Oct 16 19:59:27 volumio volumio[1038]: Volumio Calling Home Oct 16 19:59:27 volumio volumio[1038]: info: Oct 16 19:59:27 volumio volumio[1038]: ---------------------------- Client requests Volume + Oct 16 19:59:27 volumio volumio[1038]: info: VolumeController::SetAlsaVolume+ Oct 16 19:59:27 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:27 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:27 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:27 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:27 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:27 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:29 volumio volumio[1038]: info: Oct 16 19:59:29 volumio volumio[1038]: ---------------------------- Client requests Volume + Oct 16 19:59:29 volumio volumio[1038]: info: VolumeController::SetAlsaVolume+ Oct 16 19:59:29 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:29 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:29 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:29 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:29 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:29 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:31 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 19:59:31 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 19:59:37 volumio volumio[1038]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.773&uuid=8b4da437586d435a3f212bc7af0c9038" http://updates.volumio.org:7070/downloader-v1/track-device Oct 16 19:59:37 volumio volumio[1038]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 19:59:37 volumio volumio[1038]: Dload Upload Total Spent Left Speed Oct 16 19:59:37 volumio volumio[1038]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 16 19:59:37 volumio volumio[1038]: retrying in 5 seconds, trial 2 Oct 16 19:59:37 volumio volumio[1038]: Volumio Calling Home Oct 16 19:59:42 volumio volumio[1038]: info: VolumeController::SetAlsaVolume49 Oct 16 19:59:42 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:42 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:42 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:42 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:42 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:47 volumio volumio[1038]: info: Oct 16 19:59:47 volumio volumio[1038]: ---------------------------- Client requests Volume + Oct 16 19:59:47 volumio volumio[1038]: info: VolumeController::SetAlsaVolume+ Oct 16 19:59:47 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:47 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:47 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:47 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:47 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:47 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:48 volumio volumio[1038]: info: VolumeController::SetAlsaVolume0 Oct 16 19:59:48 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:48 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:48 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:48 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:48 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:51 volumio volumio[1038]: info: Oct 16 19:59:51 volumio volumio[1038]: ---------------------------- Client requests Volume - Oct 16 19:59:51 volumio volumio[1038]: info: VolumeController::SetAlsaVolume- Oct 16 19:59:51 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:51 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:51 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:51 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:51 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:51 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:53 volumio volumio[1038]: info: Oct 16 19:59:53 volumio volumio[1038]: ---------------------------- Client requests Volume - Oct 16 19:59:53 volumio volumio[1038]: info: VolumeController::SetAlsaVolume- Oct 16 19:59:53 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:53 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:53 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:53 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:53 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:53 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:54 volumio volumio[1038]: info: Oct 16 19:59:54 volumio volumio[1038]: ---------------------------- Client requests Volume + Oct 16 19:59:54 volumio volumio[1038]: info: VolumeController::SetAlsaVolume+ Oct 16 19:59:54 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:54 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:54 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:54 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:54 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:54 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:55 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-DISCONNECTED bssid=70:4d:7b:d7:30:2c reason=3 locally_generated=1 Oct 16 19:59:55 volumio dhcpcd[1015]: wlan0: carrier lost Oct 16 19:59:55 volumio dhcpcd[1015]: wlan0: deleting host route to 192.168.1.145 via 127.0.0.1 Oct 16 19:59:55 volumio dhcpcd[1015]: wlan0: deleting route to 192.168.1.0/24 Oct 16 19:59:55 volumio dhcpcd[1015]: wlan0: deleting default route via 192.168.1.1 Oct 16 19:59:55 volumio avahi-daemon[668]: Withdrawing address record for 192.168.1.145 on wlan0. Oct 16 19:59:55 volumio avahi-daemon[668]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.145. Oct 16 19:59:55 volumio avahi-daemon[668]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 16 19:59:55 volumio volumio[1038]: info: mDNS: A device disapperared from network Oct 16 19:59:55 volumio volumio[1038]: info: mDNS: Device volumio disapperared from network Oct 16 19:59:55 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 16 19:59:55 volumio volumio[1038]: info: Oct 16 19:59:55 volumio volumio[1038]: ---------------------------- Client requests Volume + Oct 16 19:59:55 volumio volumio[1038]: info: VolumeController::SetAlsaVolume+ Oct 16 19:59:55 volumio volumio[1038]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 16 19:59:55 volumio volumio[1038]: info: CoreStateMachine::pushState Oct 16 19:59:55 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 19:59:55 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 19:59:55 volumio volumio[1038]: info: CoreCommandRouter::volumioPushState Oct 16 19:59:55 volumio volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:59:57 volumio ntpd[732]: Deleting interface #3 wlan0, 192.168.1.145#123, interface stats: received=22, sent=22, dropped=0, active_time=64 secs Oct 16 19:59:57 volumio ntpd[732]: 188.168.24.22 interface 192.168.1.145 -> (none) Oct 16 19:59:57 volumio ntpd[732]: 217.170.87.229 interface 192.168.1.145 -> (none) Oct 16 19:59:57 volumio ntpd[732]: 193.106.93.116 interface 192.168.1.145 -> (none) Oct 16 19:59:57 volumio ntpd[732]: peers refreshed Oct 16 19:59:58 volumio wpa_supplicant[915]: wlan0: Trying to associate with SSID 'ASUS_5G' Oct 16 20:00:01 volumio wpa_supplicant[915]: wlan0: Associated with 70:4d:7b:d7:30:2c Oct 16 20:00:01 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=FR Oct 16 20:00:01 volumio wpa_supplicant[915]: wlan0: WPA: Key negotiation completed with 70:4d:7b:d7:30:2c [PTK=CCMP GTK=CCMP] Oct 16 20:00:01 volumio wpa_supplicant[915]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:4d:7b:d7:30:2c completed [id=0 id_str=] Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: carrier acquired Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: rebinding lease of 192.168.1.145 Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: leased 192.168.1.145 for 86400 seconds Oct 16 20:00:01 volumio avahi-daemon[668]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.145. Oct 16 20:00:01 volumio avahi-daemon[668]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: adding host route to 192.168.1.145 via 127.0.0.1 Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: adding route to 192.168.1.0/24 Oct 16 20:00:01 volumio avahi-daemon[668]: Registering new address record for 192.168.1.145 on wlan0.IPv4. Oct 16 20:00:01 volumio dhcpcd[1015]: wlan0: adding default route via 192.168.1.1 Oct 16 20:00:02 volumio ntpd[732]: Listen normally on 4 wlan0 192.168.1.145 UDP 123 Oct 16 20:00:02 volumio ntpd[732]: peers refreshed Oct 16 20:00:03 volumio volumio[1038]: Discovery: adding d3baae3b-1a70-4884-b247-f96b919a671a Oct 16 20:00:03 volumio volumio[1038]: info: mDNS: Found device Volumio Oct 16 20:00:03 volumio volumio[1038]: info: CoreCommandRouter::volumioGetState Oct 16 20:00:03 volumio volumio[1038]: info: CoreStateMachine::getState Oct 16 20:00:03 volumio volumio[1038]: info: CorePlayQueue::getTrack 0 Oct 16 20:00:03 volumio volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:00:03 volumio volumio[1038]: Error: The value NaN is not a number Oct 16 20:00:03 volumio volumio[1038]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 16 20:00:03 volumio volumio[1038]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 16 20:00:03 volumio volumio[1038]: at ControllerVolumioDiscovery.pushMultiRoomStatusUpdate (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:286:25) Oct 16 20:00:03 volumio volumio[1038]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:275:10) Oct 16 20:00:03 volumio volumio[1038]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:202:12) Oct 16 20:00:03 volumio volumio[1038]: at emitTwo (events.js:126:13) Oct 16 20:00:03 volumio volumio[1038]: at Browser.emit (events.js:214:7) Oct 16 20:00:03 volumio volumio[1038]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 16 20:00:03 volumio volumio[1038]: at next (/volumio/node_modules/mdns/lib/browser.js:104:7) Oct 16 20:00:03 volumio volumio[1038]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 16 20:00:03 volumio volumio[1038]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 16 20:00:03 volumio volumio[1038]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 16 20:00:03 volumio volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:00:03 volumio sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 19:59 Oct 16 20:00:03 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="ac2a0afddd424ea15d982b8c75eeec3a8648a313" VOLUMIO_FE_VERSION="700492006632ef61a5a17e6a3be4f37c790f883c" VOLUMIO_BE_VERSION="b2e85b2e8facd2d741f2fc40be426a34804b56fc" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue May 5 18:38:33 CEST 2020" VOLUMIO_VERSION="2.773" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="9e13d1d50d1f834c8c0294a226468074"