-- Logs begin at Thu 2019-02-14 11:12:15 CET, end at Sun 2025-05-11 08:14:07 CEST. --
May 11 08:13:00 volumio mpd[1337]: May 11 08:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 11 08:13:00 volumio systemd[1]: Started Music Player Daemon.
May 11 08:13:00 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root
May 11 08:13:00 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:00 volumio volumio[886]: error: MPD error: Error: write EPIPE
May 11 08:13:00 volumio volumio[886]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"}
May 11 08:13:01 volumio volumio[886]: info: camilladsp service started and running in background, instance 1
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA
May 11 08:13:01 volumio volumio[886]: info: FusionDsp loaded
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 08:13:01 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
May 11 08:13:01 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - Reporting Fusion DSP Enabled
May 11 08:13:01 volumio volumio[886]: info: Adding Signal Path Element [object Object]
May 11 08:13:01 volumio volumio[886]: info: Adding fusiondspeq DSP Signal Path Element
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - ---- installed callbackRead
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - If filter freq >samplerate/2 then disable it
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - Loudness is ON true
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:01 volumio systemd[1]: Started FusionDsp Daemon.
May 11 08:13:01 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 11 08:13:01 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 11 08:13:01 volumio volumio[886]: error: Upnp client error: Error: This socket has been ended by the other party
May 11 08:13:01 volumio volumio[886]: info: Shairport-Sync Started
May 11 08:13:01 volumio volumio[886]: Error adding Membership: Error: addMembership EINVAL
May 11 08:13:01 volumio volumio[886]: info: Shairport-Sync Started
May 11 08:13:01 volumio volumio[886]: info: Shairport-Sync Started
May 11 08:13:01 volumio volumio[886]: info: Shairport-Sync Started
May 11 08:13:01 volumio volumio[886]: info: minidlna: systemctl start minidlna.service succeeded.
May 11 08:13:01 volumio volumio[886]: info: touch_display: systemctl daemon-reload succeeded.
May 11 08:13:01 volumio sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
May 11 08:13:01 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:02 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:02 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:02 volumio volumio[886]: Upnp client error: Error: This socket has been ended by the other party
May 11 08:13:02 volumio volumio[886]: error: MPD error: The expression evaluated to a falsy value:
May 11 08:13:02 volumio volumio[886]: assert.ok(self.idling)
May 11 08:13:02 volumio volumio[886]: error: The expression evaluated to a falsy value:
May 11 08:13:02 volumio volumio[886]: assert.ok(self.idling)
May 11 08:13:02 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41
May 11 08:13:02 volumio volumio[886]: info: Starting Shairport Sync
May 11 08:13:02 volumio systemd[1]: Started Volumio Kiosk.
May 11 08:13:02 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 11 08:13:02 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:02 volumio volumio[886]: info: FusionDsp - {"Reload":{"result":"Ok"}}
May 11 08:13:02 volumio volumio[886]: error: updateQueue error: null
May 11 08:13:02 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root
May 11 08:13:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 11 08:13:02 volumio systemd[1]: shairport-sync.service: Succeeded.
May 11 08:13:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 11 08:13:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 11 08:13:02 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root
May 11 08:13:02 volumio volumio[886]: info: Shairport-Sync Started
May 11 08:13:02 volumio volumio[886]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
May 11 08:13:02 volumio volumio[886]: info: touch_display: Volumio Kiosk started.
May 11 08:13:02 volumio startx[1376]: X.Org X Server 1.20.4
May 11 08:13:02 volumio startx[1376]: X Protocol Version 11, Revision 0
May 11 08:13:02 volumio startx[1376]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian
May 11 08:13:02 volumio startx[1376]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l
May 11 08:13:02 volumio startx[1376]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1360 bcm2708_fb.fbheight=768 bcm2708_fb.fbswap=1 smsc95xx.macaddr=2C:CF:67:A0:8A:FE vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=31894860-154d-4623-adeb-3744ffaa6b75 imgfile=/volumio_current.sqsh bootpart=UUID=8E61-2966 datapart=UUID=ddd2acb8-eb82-4115-8903-a4448dfe67a8 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no
May 11 08:13:02 volumio startx[1376]: Build Date: 04 April 2023 07:50:56AM
May 11 08:13:02 volumio startx[1376]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support)
May 11 08:13:02 volumio startx[1376]: Current version of pixman: 0.36.0
May 11 08:13:02 volumio startx[1376]: Before reporting problems, check http://wiki.x.org
May 11 08:13:02 volumio startx[1376]: to make sure that you have the latest version.
May 11 08:13:02 volumio startx[1376]: Markers: (--) probed, (**) from config file, (==) default setting,
May 11 08:13:02 volumio startx[1376]: (++) from command line, (!!) notice, (II) informational,
May 11 08:13:02 volumio startx[1376]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
May 11 08:13:02 volumio startx[1376]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun May 11 08:13:02 2025
May 11 08:13:02 volumio startx[1376]: (==) Using config directory: "/etc/X11/xorg.conf.d"
May 11 08:13:02 volumio startx[1376]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
May 11 08:13:02 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:02 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:02 volumio volumio[886]: info: Completed starting Core Plugins
May 11 08:13:02 volumio volumio[886]: info: -------------------------------------------
May 11 08:13:02 volumio volumio[886]: info: ----- MyVolumio plugins startup ----
May 11 08:13:02 volumio volumio[886]: info: -------------------------------------------
May 11 08:13:02 volumio volumio[886]: info: [MyVolumio PluginManager] Fetching plans data....
May 11 08:13:02 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:02 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:02 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 42
May 11 08:13:02 volumio volumio[886]: info: touch_display: X display number found: 0
May 11 08:13:02 volumio volumio[886]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
May 11 08:13:02 volumio volumio[886]: info: touch_display: X display number found: 0
May 11 08:13:03 volumio volumio[886]: info: CoreCommandRouter::getUIConfigOnPlugin
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:03 volumio volumio[886]: info: CoreCommandRouter::getUIConfigOnPlugin
May 11 08:13:03 volumio volumio[886]: info: CoreCommandRouter::getUIConfigOnPlugin
May 11 08:13:03 volumio volumio[886]: info: CoreCommandRouter::getUIConfigOnPlugin
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - no preset used
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:03 volumio volumio[886]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json
May 11 08:13:03 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43
May 11 08:13:04 volumio volumio[886]: info: touch_display: Setting screensaver timeout to 10 seconds.
May 11 08:13:05 volumio volumio[886]: info: CoreCommandRouter::Close All Modals sent
May 11 08:13:07 volumio systemd[1]: systemd-timedated.service: Succeeded.
May 11 08:13:08 volumio volumio-remote-updater[630]: [2025-05-11 08:13:08] [connect] Successful connection
May 11 08:13:08 volumio volumio-remote-updater[630]: [2025-05-11 08:13:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746943988 101
May 11 08:13:08 volumio volumio[886]: 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: 44
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 11 08:13:10 volumio volumio[886]: info: Adding plugin bluetooth to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: Adding plugin multiroom to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: Adding plugin metavolumio to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: Adding plugin cd_controller to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: Adding plugin smart_inputs to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: Adding plugin tidalconnect to MyMusic Plugins
May 11 08:13:10 volumio volumio[886]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 11 08:13:12 volumio volumio[886]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 11 08:13:12 volumio volumio[886]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 11 08:13:12 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:12 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:12 volumio volumio[886]: info: Starting MyVolumio Remote Streaming Endpoints
May 11 08:13:12 volumio volumio[886]: info: MyVolumio login type: Token
May 11 08:13:12 volumio volumio[886]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 11 08:13:12 volumio volumio[886]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 11 08:13:13 volumio volumio[886]: info: Starting Streaming Service Transparent Proxy
May 11 08:13:13 volumio volumio[886]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 11 08:13:13 volumio volumio[886]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 11 08:13:13 volumio volumio[886]: info: Streaming services startup
May 11 08:13:13 volumio volumio[886]: info: Starting Streaming Daemon
May 11 08:13:13 volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 11 08:13:13 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:13 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root
May 11 08:13:13 volumio volumio[886]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 11 08:13:13 volumio volumio[886]: error: Cannot start Volumio Streaming Daemon
May 11 08:13:13 volumio volumio[886]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 11 08:13:13 volumio volumio[886]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 11 08:13:13 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 11 08:13:13 volumio volumio[886]: info: Discovery: Getting this device information
May 11 08:13:13 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:13 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:13 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 11 08:13:13 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:13 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:13 volumio volumio[886]: info: Listing playlists
May 11 08:13:13 volumio volumio[886]: info: Listing playlists
May 11 08:13:13 volumio volumio[886]: info: [ASDebug] Port: 24
May 11 08:13:13 volumio volumio[886]: info: [ASDebug] Inverted: false
May 11 08:13:13 volumio volumio[886]: info: [ASDebug] Delay: 720
May 11 08:13:14 volumio volumio[886]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 11 08:13:14 volumio volumio[886]: STREAMING PROXY: Starting server on port 3245
May 11 08:13:14 volumio volumio[886]: Node JS runtime: 14
May 11 08:13:14 volumio volumio[886]: info: MyVolumio token set successfully
May 11 08:13:14 volumio volumio[886]: info: MYVOLUMIO: Adding device
May 11 08:13:14 volumio volumio[886]: info: MYVOLUMIO: Evaluating Server
May 11 08:13:15 volumio volumio[886]: info: MyVolumio status changed
May 11 08:13:15 volumio volumio[886]: info: Streaming services startup
May 11 08:13:15 volumio volumio[886]: info: Starting Streaming Daemon
May 11 08:13:15 volumio volumio[886]: info: Removing browser output: myVolumio user plan is not superstar
May 11 08:13:15 volumio volumio[886]: info: Removing audio output:
May 11 08:13:15 volumio volumio[886]: info: Stoppping Tunnel 1
May 11 08:13:15 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 11 08:13:15 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:15 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root
May 11 08:13:15 volumio sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 11 08:13:15 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 11 08:13:15 volumio volumio[886]: error: Cannot start Volumio Streaming Daemon
May 11 08:13:15 volumio volumio[886]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 11 08:13:15 volumio volumio[886]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 11 08:13:15 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root
May 11 08:13:15 volumio volumio[886]: info: Remote SSH Stopped
May 11 08:13:15 volumio volumio[886]: info: Setting Geolocation for MyVolumio to eu4
May 11 08:13:15 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:15 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:15 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:15 volumio volumio[886]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 11 08:13:16 volumio volumio[886]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates.
May 11 08:13:16 volumio volumio[886]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:95:19)
May 11 08:13:16 volumio volumio[886]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:37)
May 11 08:13:16 volumio volumio[886]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13
May 11 08:13:16 volumio volumio[886]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5)
May 11 08:13:16 volumio volumio[886]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:149:13)
May 11 08:13:16 volumio volumio[886]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:119:3)
May 11 08:13:16 volumio volumio[886]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5)
May 11 08:13:16 volumio volumio[886]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15
May 11 08:13:16 volumio volumio[886]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14)
May 11 08:13:16 volumio volumio[886]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14)
May 11 08:13:16 volumio volumio[886]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3)
May 11 08:13:16 volumio volumio[886]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10)
May 11 08:13:16 volumio volumio[886]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3)
May 11 08:13:16 volumio volumio[886]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12)
May 11 08:13:16 volumio volumio[886]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5)
May 11 08:13:16 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 45
May 11 08:13:16 volumio volumio[886]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo
May 11 08:13:16 volumio volumio[886]: info: Updating MyVolumio device info
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:16 volumio volumio[886]: info: Listing playlists
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::volumioGetQueue
May 11 08:13:16 volumio volumio[886]: info: CoreStateMachine::getQueue
May 11 08:13:16 volumio volumio[886]: info: CorePlayQueue::getQueue
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::volumioGetVisibleSources
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:16 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:16 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 11 08:13:16 volumio volumio[886]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"NRJ HITS 90","uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","service":""}
May 11 08:13:17 volumio volumio[886]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 11 08:13:18 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 11 08:13:18 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:18 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 11 08:13:19 volumio volumio-remote-updater[630]: Test mode enabled
May 11 08:13:19 volumio volumio-remote-updater[630]: No alpha test mode
May 11 08:13:19 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 11 08:13:19 volumio volumio[886]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Slow down CD read speed
\n- Fix NAS Mounting
\n
\nNEW ADDITIONS
\n\n- Qobuz Connect Ready
\n
\n","title":"Update v3.808","updateavailable":true}
May 11 08:13:19 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 11 08:13:19 volumio volumio[886]: info: MYVOLUMIO: Adding device
May 11 08:13:19 volumio volumio[886]: info: MYVOLUMIO: Evaluating Server
May 11 08:13:20 volumio volumio[886]: info: Setting Geolocation for MyVolumio to eu4
May 11 08:13:20 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:20 volumio volumio[886]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 11 08:13:21 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:21 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:21 volumio volumio[886]: info: Updating MyVolumio device info
May 11 08:13:21 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:22 volumio volumio[886]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 11 08:13:27 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 11 08:13:27 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
May 11 08:13:27 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:27 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:31 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:31 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:31 volumio volumio[886]: info: Listing playlists
May 11 08:13:31 volumio volumio[886]: info: Listing playlists
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::volumioGetBrowseSources
May 11 08:13:32 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 11 08:13:33 volumio volumio[886]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 11 08:13:33 volumio volumio[886]: info: Completed starting MyVolumio Plugin
May 11 08:13:33 volumio volumio[886]: [Metrics] CommandRouter: 58s 721.02ms
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::volumiosetStartupVolume
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 11 08:13:33 volumio volumio[886]: info: VolumeController:: Setting startup Volume 60
May 11 08:13:33 volumio volumio[886]: info: VolumeController::SetAlsaVolume60
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::Close All Modals sent
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::Close All Modals sent
May 11 08:13:33 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:13:33 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:33 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:13:33 volumio volumio[886]: info: [ASDebug] CurState: stop PrevState: stop
May 11 08:13:33 volumio volumio[886]: info: FusionDsp - volume level for loudness 60 gain applied 0.00
May 11 08:13:33 volumio volumio[886]: info: FusionDsp - If filter freq >samplerate/2 then disable it
May 11 08:13:33 volumio volumio[886]: info: FusionDsp - Loudness is ON true
May 11 08:13:33 volumio volumio[886]: info: ONKYO-CONTROL: New state: {"status":"stop","position":0,"title":"NRJ HITS 90","albumart":"http://cdn-profiles.tunein.com/s259316/images/logoq.jpg?t=159438","uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","trackType":"webradio","seek":0,"samplerate":"","bitdepth":"","channels":0,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":60,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"webradio","updatedb":false,"volatile":false,"service":"webradio"} connection: {"reconnect":true,"verify_commands":false,"port":60128,"host":"192.168.31.12","model":"TX-NR809"}
May 11 08:13:33 volumio volumio[886]: info: FusionDsp - {"Reload":{"result":"Ok"}}
May 11 08:13:34 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 11 08:13:34 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 11 08:13:34 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 11 08:13:34 volumio ntpd[732]: Soliciting pool server 212.83.158.83
May 11 08:13:35 volumio ntpd[732]: Soliciting pool server 129.250.35.251
May 11 08:13:35 volumio ntpd[732]: Soliciting pool server 164.132.80.137
May 11 08:13:35 volumio ntpd[732]: Soliciting pool server 195.154.185.215
May 11 08:13:36 volumio ntpd[732]: Soliciting pool server 45.132.96.81
May 11 08:13:36 volumio ntpd[732]: Soliciting pool server 146.59.88.209
May 11 08:13:36 volumio ntpd[732]: Soliciting pool server 162.159.200.123
May 11 08:13:37 volumio ntpd[732]: Soliciting pool server 51.255.95.80
May 11 08:13:37 volumio ntpd[732]: Soliciting pool server 162.159.200.1
May 11 08:13:37 volumio ntpd[732]: Soliciting pool server 92.222.117.115
May 11 08:13:37 volumio ntpd[732]: Soliciting pool server 185.254.100.25
May 11 08:13:38 volumio ntpd[732]: Soliciting pool server 5.189.185.174
May 11 08:13:38 volumio ntpd[732]: Soliciting pool server 37.59.63.125
May 11 08:13:38 volumio ntpd[732]: Soliciting pool server 51.158.147.185
May 11 08:13:39 volumio ntpd[732]: Soliciting pool server 82.64.81.218
May 11 08:13:39 volumio ntpd[732]: Soliciting pool server 2a02:c207:0:4041::1
May 11 08:13:40 volumio volumio[886]: info: BOOT COMPLETED
May 11 08:13:41 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:41 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:41 volumio volumio[886]: info: Starting Uninstall of plugin system_controller - ampswitch
May 11 08:13:41 volumio volumio[886]: info: Uninstalling plugin ampswitch
May 11 08:13:41 volumio volumio[886]: info: [ASDebug] Port: 24
May 11 08:13:41 volumio volumio[886]: info: [ASDebug] Inverted: false
May 11 08:13:41 volumio volumio[886]: info: [ASDebug] Delay: 720
May 11 08:13:42 volumio ntpd[732]: receive: Unexpected origin timestamp 0xebcabe95.ba550538 does not match aorg 0000000000.00000000 from server@185.254.100.25 xmt 0xebcabe96.d50d5d26
May 11 08:13:42 volumio ntpd[732]: receive: Unexpected origin timestamp 0xebcabe95.ba51d0ea does not match aorg 0000000000.00000000 from server@5.189.185.174 xmt 0xebcabe96.d5052132
May 11 08:13:42 volumio ntpd[732]: receive: Unexpected origin timestamp 0xebcabe95.ba566055 does not match aorg 0000000000.00000000 from server@129.250.35.251 xmt 0xebcabe96.ddc61904
May 11 08:13:52 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:52 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:13:52 volumio volumio[886]: info: Listing playlists
May 11 08:13:52 volumio volumio[886]: info: Listing playlists
May 11 08:13:53 volumio volumio[886]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
May 11 08:13:53 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:13:53 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:02 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:14:02 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:03 volumio volumio[886]: info: CoreCommandRouter::volumioGetState
May 11 08:14:03 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:05 volumio volumio[886]: info: CoreCommandRouter::volumioPlay
May 11 08:14:05 volumio volumio[886]: info: CoreStateMachine::play index undefined
May 11 08:14:05 volumio volumio[886]: info: CoreStateMachine::setConsumeUpdateService undefined
May 11 08:14:05 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:05 volumio volumio[886]: info: CoreStateMachine::startPlaybackTimer
May 11 08:14:05 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:05 volumio volumio[886]: info: [1746944045116] ControllerWebradio::clearAddPlayTrack
May 11 08:14:05 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand stop
May 11 08:14:05 volumio volumio[886]: info: sendMpdCommand stop took 2 milliseconds
May 11 08:14:05 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand clear
May 11 08:14:05 volumio volumio[886]: info:
May 11 08:14:05 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:05 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:05 volumio volumio[886]: info: sendMpdCommand clear took 2 milliseconds
May 11 08:14:05 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand load "https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio"
May 11 08:14:05 volumio volumio[886]: info:
May 11 08:14:05 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:05 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:05 volumio volumio[886]: error: updateQueue error: null
May 11 08:14:05 volumio volumio[886]: info: ------------------------------ 5ms
May 11 08:14:06 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand add "https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio"
May 11 08:14:06 volumio volumio[886]: error: updateQueue error: null
May 11 08:14:06 volumio volumio[886]: info: ------------------------------ 1471ms
May 11 08:14:06 volumio volumio[886]: info:
May 11 08:14:06 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:06 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:06 volumio volumio[886]: info: sendMpdCommand add "https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio" took 2 milliseconds
May 11 08:14:06 volumio volumio[886]: info: CoreStateMachine::setConsumeUpdateService mpd
May 11 08:14:06 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand play
May 11 08:14:06 volumio volumio[886]: info:
May 11 08:14:06 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:06 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:06 volumio volumio[886]: info: ------------------------------ 5ms
May 11 08:14:06 volumio volumio[886]: info: sendMpdCommand play took 3 milliseconds
May 11 08:14:06 volumio volumio[886]: info: ------------------------------ 3ms
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces state update: player
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::getState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces state update: player
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::getState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status
May 11 08:14:07 volumio volumio[886]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
May 11 08:14:07 volumio volumio[886]: info: FusionDsp - ---- read samplerate from file: 44100
May 11 08:14:07 volumio volumio[886]: info: camilladsp stopping service pid 1352...
May 11 08:14:07 volumio volumio[886]: info: camilladsp service terminated, instance 1
May 11 08:14:07 volumio volumio[886]: info: FusionDsp - If filter freq >samplerate/2 then disable it
May 11 08:14:07 volumio volumio[886]: info: FusionDsp - Loudness is ON true
May 11 08:14:07 volumio volumio[886]: info: camilladsp service started and running in background, instance 1
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:07 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces state update: player
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand status took 46 milliseconds
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand status took 44 milliseconds
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::getState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces system playlist update
May 11 08:14:07 volumio volumio[886]: info: Ignoring MPD Status Update
May 11 08:14:07 volumio volumio[886]: info:
May 11 08:14:07 volumio volumio[886]: ---------------------------- MPD announces state update: player
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::getState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand status
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 31ms
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand status took 28 milliseconds
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand playlistinfo took 28 milliseconds
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand playlistinfo took 27 milliseconds
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 27ms
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand status took 26 milliseconds
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseTrackInfo
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseState
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::servicePushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: verbose: 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":"128 Kbps","isStreaming":false,"title":"ou627gxp336f?origine=tuneinradio","artist":"NRJ Hits 90 mp3 - RK","album":null,"uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","trackType":""}
May 11 08:14:07 volumio volumio[886]: verbose: CURRENT POSITION 0
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState stateService play
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus stop
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::servicePushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":605,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ou627gxp336f?origine=tuneinradio","artist":"NRJ Hits 90 mp3 - RK","album":null,"uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","trackType":""}
May 11 08:14:07 volumio volumio[886]: verbose: CURRENT POSITION 0
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState stateService play
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus play
May 11 08:14:07 volumio volumio[886]: info: Received an update from plugin. extracting info from payload
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 106ms
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 119ms
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand playlistinfo took 49 milliseconds
May 11 08:14:07 volumio volumio[886]: info: sendMpdCommand playlistinfo took 46 milliseconds
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseTrackInfo
May 11 08:14:07 volumio volumio[886]: verbose: ControllerMpd::parseTrackInfo
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::servicePushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ou627gxp336f?origine=tuneinradio","artist":"NRJ Hits 90 mp3 - RK","album":null,"uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","trackType":""}
May 11 08:14:07 volumio volumio[886]: verbose: CURRENT POSITION 0
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState stateService play
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus play
May 11 08:14:07 volumio volumio[886]: info: Received an update from plugin. extracting info from payload
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: ControllerMpd::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::servicePushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ou627gxp336f?origine=tuneinradio","artist":"NRJ Hits 90 mp3 - RK","album":null,"uri":"https://streaming.nrjaudio.fm/ou627gxp336f?origine=tuneinradio","trackType":""}
May 11 08:14:07 volumio volumio[886]: verbose: CURRENT POSITION 0
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState stateService play
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::syncState currentStatus play
May 11 08:14:07 volumio volumio[886]: info: Received an update from plugin. extracting info from payload
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CorePlayQueue::getTrack 0
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: CoreStateMachine::pushState
May 11 08:14:07 volumio volumio[886]: info: CoreCommandRouter::volumioPushState
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 162ms
May 11 08:14:07 volumio volumio[886]: info: ------------------------------ 158ms
May 11 08:14:07 volumio volumio[886]: info: [ASDebug] CurState: play PrevState: stop
May 11 08:14:07 volumio volumio[886]: info: [ASDebug] Togle GPIO: ON
May 11 08:14:07 volumio volumio[886]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 11 08:14:07 volumio volumio[886]: Error: EBADF: bad file descriptor, write
May 11 08:14:07 volumio volumio[886]: at Object.writeSync (fs.js:711:3)
May 11 08:14:07 volumio volumio[886]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8)
May 11 08:14:07 volumio volumio[886]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23)
May 11 08:14:07 volumio volumio[886]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86)
May 11 08:14:07 volumio volumio[886]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 11 08:14:07 volumio volumio[886]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10)
May 11 08:14:07 volumio volumio[886]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12)
May 11 08:14:07 volumio volumio[886]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 11 08:14:07 volumio volumio[886]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 11 08:14:07 volumio volumio[886]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8)
May 11 08:14:07 volumio volumio[886]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 11 08:14:07 volumio volumio[886]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20)
May 11 08:14:07 volumio volumio[886]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12)
May 11 08:14:07 volumio volumio[886]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16)
May 11 08:14:07 volumio volumio[886]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15)
May 11 08:14:07 volumio volumio[886]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) {
May 11 08:14:07 volumio volumio[886]: errno: -9,
May 11 08:14:07 volumio volumio[886]: syscall: 'write',
May 11 08:14:07 volumio volumio[886]: code: 'EBADF'
May 11 08:14:07 volumio volumio[886]: }
May 11 08:14:07 volumio volumio[886]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 11 08:14:07 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 08:13
May 11 08:14:07 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST"
VOLUMIO_VERSION="3.799"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"