-- Logs begin at Mon 2024-09-02 10:44:55 UTC, end at Mon 2024-09-02 13:46:14 UTC. -- Sep 02 13:44:03 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:10 extremejukebox2 volumio[18945]: Searching plugin music_service/mpd Sep 02 13:44:10 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 02 13:44:11 extremejukebox2 volumio[18945]: Searching plugin music_service/mpd Sep 02 13:44:11 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 02 13:44:12 extremejukebox2 volumio[18945]: Searching plugin music_service/mpd Sep 02 13:44:12 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , search Sep 02 13:44:17 extremejukebox2 volumio[18945]: Cannot compose Albumart path Sep 02 13:44:18 extremejukebox2 volumio[18945]: Cannot compose Albumart path Sep 02 13:44:18 extremejukebox2 volumio[18945]: info: All search sources collected, pushing search results Sep 02 13:44:18 extremejukebox2 volumio[18945]: info: All search sources collected, pushing search results Sep 02 13:44:18 extremejukebox2 volumio[18945]: info: All search sources collected, pushing search results Sep 02 13:44:22 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:22 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:22 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:24 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:24 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:27 extremejukebox2 volumio[18945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:37 extremejukebox2 dhcpcd[889]: wlan0: carrier lost Sep 02 13:44:37 extremejukebox2 wpa_supplicant[877]: wlan0: Associated with d4:35:38:f8:16:c0 Sep 02 13:44:37 extremejukebox2 wpa_supplicant[877]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:35:38:f8:16:c0 completed [id=2 id_str=] Sep 02 13:44:37 extremejukebox2 wpa_supplicant[877]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 02 13:44:37 extremejukebox2 avahi-daemon[628]: Withdrawing address record for 192.168.1.3 on wlan0. Sep 02 13:44:37 extremejukebox2 avahi-daemon[628]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3. Sep 02 13:44:37 extremejukebox2 avahi-daemon[628]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 02 13:44:37 extremejukebox2 dhcpcd[889]: wlan0: deleting route to 192.168.1.0/24 Sep 02 13:44:37 extremejukebox2 dhcpcd[889]: wlan0: deleting default route via 192.168.1.1 Sep 02 13:44:37 extremejukebox2 volumio[18945]: info: Discovery: A device disappeared from network Sep 02 13:44:37 extremejukebox2 dhcpcd[889]: wlan0: carrier acquired Sep 02 13:44:37 extremejukebox2 dhcpcd[889]: wlan0: IAID dd:b8:e2:20 Sep 02 13:44:38 extremejukebox2 dhcpcd[889]: wlan0: rebinding lease of 192.168.1.3 Sep 02 13:44:38 extremejukebox2 dhcpcd[889]: wlan0: soliciting an IPv6 router Sep 02 13:44:38 extremejukebox2 volumio[18945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:44:38 extremejukebox2 volumio[18945]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 02 13:44:38 extremejukebox2 volumio[18945]: at doSend (dgram.js:692:16) Sep 02 13:44:38 extremejukebox2 volumio[18945]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 02 13:44:38 extremejukebox2 volumio[18945]: at afterDns (dgram.js:638:5) Sep 02 13:44:38 extremejukebox2 volumio[18945]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 02 13:44:38 extremejukebox2 volumio[18945]: errno: -101, Sep 02 13:44:38 extremejukebox2 volumio[18945]: code: 'ENETUNREACH', Sep 02 13:44:38 extremejukebox2 volumio[18945]: syscall: 'send', Sep 02 13:44:38 extremejukebox2 volumio[18945]: address: '255.255.255.255', Sep 02 13:44:38 extremejukebox2 volumio[18945]: port: 3483 Sep 02 13:44:38 extremejukebox2 volumio[18945]: } Sep 02 13:44:38 extremejukebox2 volumio[18945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:44:39 extremejukebox2 sudo[22410]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:39 extremejukebox2 sudo[22410]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:39 extremejukebox2 sudo[22410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 13:43 Sep 02 13:44:39 extremejukebox2 sudo[22410]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:39 extremejukebox2 sudo[22410]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:39 extremejukebox2 volumio[18945]: sudo: unable to resolve host extremejukebox2: System error Sep 02 13:44:39 extremejukebox2 ntpd[778]: Deleting interface #93 wlan0, 192.168.1.3#123, interface stats: received=75, sent=100, dropped=0, active_time=998 secs Sep 02 13:44:39 extremejukebox2 ntpd[778]: 93.94.88.50 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 212.45.144.88 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 212.6.50.243 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 212.45.144.206 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 37.247.53.178 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 95.230.240.5 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 93.44.243.48 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 93.44.146.104 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 185.19.184.35 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 162.159.200.1 local addr 192.168.1.3 -> Sep 02 13:44:39 extremejukebox2 ntpd[778]: 185.157.229.254 local addr 192.168.1.3 -> Sep 02 13:44:40 extremejukebox2 dhcpcd[889]: wlan0: probing address 192.168.1.3/24 Sep 02 13:44:40 extremejukebox2 vtcs[19828]: [2024-09-02 13:44:40.895] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Sep 02 13:44:40 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:44:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 13:44:40 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:44:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 13:44:40 extremejukebox2 go-librespot[19259]: time="2024-09-02T13:44:40Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 02 13:44:40 extremejukebox2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 02 13:44:40 extremejukebox2 ntfs-3g[19022]: Unmounting /dev/sda1 (Daphile Music) Sep 02 13:44:40 extremejukebox2 systemd[1]: media-Daphile_Music.mount: Succeeded. Sep 02 13:44:40 extremejukebox2 systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 02 13:44:41 extremejukebox2 systemd[1]: Started dynamicswap service. Sep 02 13:44:41 extremejukebox2 systemd[1]: dynamicswap.service: Succeeded. Sep 02 13:44:41 extremejukebox2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 13:44:41 extremejukebox2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 40. Sep 02 13:44:41 extremejukebox2 systemd[1]: Started dynamicswap service. Sep 02 13:44:41 extremejukebox2 systemd[1]: Stopped Volumio Backend Module. Sep 02 13:44:41 extremejukebox2 systemd[1]: Started Volumio Backend Module. Sep 02 13:44:41 extremejukebox2 systemd[1]: dynamicswap.service: Succeeded. Sep 02 13:44:42 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:44:42 extremejukebox2 volumio[22424]: info: ----- Volumio3 ---- Sep 02 13:44:42 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:44:42 extremejukebox2 volumio[22424]: info: ----- System startup ---- Sep 02 13:44:42 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: MYVOLUMIO Environment detected Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Plugin folders cleanup Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning into folder /volumio/app/plugins/ Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category audio_interface Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category miscellanea Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category music_service Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category plugins.json Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category system_controller Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category user_interface Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning into folder /data/plugins/ Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category audio_interface Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category music_service Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category system_controller Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Scanning category user_interface Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Plugin folders cleanup completed Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: ----- Core plugins startup ---- Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Adding plugin upnp to MyMusic Plugins Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Loading plugins from folder /data/plugins/ Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Loading plugin "system"... Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Loading plugin "appearance"... Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Loading plugin "network"... Sep 02 13:44:43 extremejukebox2 volumio[22424]: info: Refreshing Cached IP Addresses Sep 02 13:44:44 extremejukebox2 sudo[22450]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:44 extremejukebox2 sudo[22450]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:44 extremejukebox2 sudo[22450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:44:44 extremejukebox2 sudo[22450]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:44 extremejukebox2 sudo[22450]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:44 extremejukebox2 sudo[22452]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:44 extremejukebox2 sudo[22452]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:44 extremejukebox2 sudo[22452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:44:44 extremejukebox2 sudo[22452]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Loading plugin "services"... Sep 02 13:44:44 extremejukebox2 sudo[22452]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Loading plugin "alsa_controller"... Sep 02 13:44:44 extremejukebox2 sudo[22456]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:44 extremejukebox2 sudo[22456]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:44 extremejukebox2 sudo[22456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 02 13:44:44 extremejukebox2 sudo[22456]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Loading plugin "wizard"... Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Loading plugin "networkfs"... Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Starting Udev Watcher for removable devices Sep 02 13:44:44 extremejukebox2 sudo[22463]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:44 extremejukebox2 sudo[22463]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:44 extremejukebox2 sudo[22463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //undefined/\\\\DESKTOP-980IALB\\Musica /mnt/NAS/musica Sep 02 13:44:44 extremejukebox2 sudo[22463]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Ignoring mount for partition: boot Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Ignoring mount for partition: volumio Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Ignoring mount for partition: volumio_data Sep 02 13:44:44 extremejukebox2 sudo[22463]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:44 extremejukebox2 volumio[22424]: info: Mounting Device Daphile_Music Sep 02 13:44:44 extremejukebox2 sudo[22471]: volumio : unable to resolve host extremejukebox2 Sep 02 13:44:44 extremejukebox2 sudo[22471]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 02 13:44:44 extremejukebox2 sudo[22471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Daphile_Music -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:44:44 extremejukebox2 sudo[22471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:45 extremejukebox2 dhcpcd[889]: wlan0: leased 192.168.1.3 for 86400 seconds Sep 02 13:44:45 extremejukebox2 avahi-daemon[628]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3. Sep 02 13:44:45 extremejukebox2 avahi-daemon[628]: New relevant interface wlan0.IPv4 for mDNS. Sep 02 13:44:45 extremejukebox2 dhcpcd[889]: wlan0: adding route to 192.168.1.0/24 Sep 02 13:44:45 extremejukebox2 avahi-daemon[628]: Registering new address record for 192.168.1.3 on wlan0.IPv4. Sep 02 13:44:45 extremejukebox2 dhcpcd[889]: wlan0: adding default route via 192.168.1.1 Sep 02 13:44:45 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:44:45] [connect] Successful connection Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Mounted /dev/sda1 (Read-Only, label "Daphile Music", NTFS 3.1) Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,ro,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Global ownership and permissions enforced, configuration type 7 Sep 02 13:44:46 extremejukebox2 ntfs-3g[22497]: Unmounting /dev/sda1 (Daphile Music) Sep 02 13:44:46 extremejukebox2 sudo[22471]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:46 extremejukebox2 volumio[22424]: sudo: unable to resolve host extremejukebox2: System error Sep 02 13:44:46 extremejukebox2 volumio[22424]: The disk contains an unclean file system (0, 0). Sep 02 13:44:46 extremejukebox2 volumio[22424]: Metadata kept in Windows cache, refused to mount. Sep 02 13:44:46 extremejukebox2 volumio[22424]: Falling back to read-only mount because the NTFS partition is in an Sep 02 13:44:46 extremejukebox2 volumio[22424]: unsafe state. Please resume and shutdown Windows fully (no hibernation Sep 02 13:44:46 extremejukebox2 volumio[22424]: or fast restarting.) Sep 02 13:44:46 extremejukebox2 volumio[22424]: Could not mount read-write, trying read-only Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "volumio_command_line_client"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "upnp"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: [1725284686353] Starting Upmpd Daemon Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "my_music"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "mpd"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "upnp_browser"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "alarm-clock"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "airplay_emulation"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Starting Shairport Sync Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "last_100"... Sep 02 13:44:46 extremejukebox2 volumio[22424]: info: Loading plugin "webradio"... Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Loading plugin "i2s_dacs"... Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: I2S DAC not set, start Auto-detection Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Loading plugin "volumiodiscovery"... Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** For more information see Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:44:47 extremejukebox2 volumio[22424]: *** WARNING *** For more information see Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** For more information see Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:44:47 extremejukebox2 node[22424]: *** WARNING *** For more information see Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Discovery: Started advertising with name: ExtremeJukebox_2 Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Loading plugin "calmradio"... Sep 02 13:44:47 extremejukebox2 ntpd[778]: Listen normally on 94 wlan0 192.168.1.3:123 Sep 02 13:44:47 extremejukebox2 ntpd[778]: new interface(s) found: waking up resolver Sep 02 13:44:47 extremejukebox2 volumio[22424]: info: Loading plugin "spop"... Sep 02 13:44:47 extremejukebox2 sudo[22456]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:48 extremejukebox2 volumio[22424]: info: Loading plugin "squeezelite_mc"... Sep 02 13:44:48 extremejukebox2 ntpd[778]: Soliciting pool server 2a00:6d41:200:2::11 Sep 02 13:44:48 extremejukebox2 volumio[22424]: info: Loading plugin "youtube2"... Sep 02 13:44:49 extremejukebox2 volumio[22424]: info: Loading plugin "ytmusic"... Sep 02 13:44:50 extremejukebox2 volumio[22424]: info: Loading plugin "autostart"... Sep 02 13:44:50 extremejukebox2 volumio[22424]: info: Applying required configuration parameters for plugin autostart Sep 02 13:44:50 extremejukebox2 volumio[22424]: info: AutoStart - onVolumioStart - read config.json Sep 02 13:44:50 extremejukebox2 volumio[22424]: info: Loading plugin "now_playing"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "outputs"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "albumart"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Plugin example_plugin is not enabled Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "inputs"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "updater_comm"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Plugin mpdemulation is not enabled Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "rest_api"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "websocket"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Starting Socket.io Server version 2.3.0 Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "fusiondsp"... Sep 02 13:44:51 extremejukebox2 volumio[22424]: Forking 3 albumart workers Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 13:44:51 extremejukebox2 volumio[22424]: info: Loading plugin "80s80s"... Sep 02 13:44:52 extremejukebox2 volumio[22424]: info: Applying required configuration parameters for plugin 80s80s Sep 02 13:44:52 extremejukebox2 volumio[22424]: info: [1725284692037] [80s80s] API delay: 30 Sep 02 13:44:52 extremejukebox2 volumio[22424]: info: Loading plugin "Systeminfo"... Sep 02 13:44:52 extremejukebox2 volumio[22424]: Starting albumart workers Sep 02 13:44:52 extremejukebox2 volumio[22424]: info: Loading plugin "touch_display"... Sep 02 13:44:52 extremejukebox2 volumio[22424]: Starting albumart workers Sep 02 13:44:52 extremejukebox2 volumio[22424]: Starting albumart workers Sep 02 13:44:52 extremejukebox2 volumio[22424]: info: Applying required configuration parameters for plugin touch_display Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: Loading i18n strings for locale it Sep 02 13:44:53 extremejukebox2 volumio[22424]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 02 13:44:53 extremejukebox2 volumio[22424]: Updating browse sources language Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::initPlayerControls Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: Express server listening on port 3000 Sep 02 13:44:53 extremejukebox2 volumio[22424]: [Metrics] WebUI: 11s 146.48ms Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::resetVolumioState Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::getcurrentVolume Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: Cannot mount NAS musica at system boot, trial number 1 ,retrying in 5 seconds Sep 02 13:44:53 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 02 13:44:53 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: Reloading queue from file Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: VolumeController:: Volume=100 Mute =false Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::pushState Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPushState Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::updateTrackBlock Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrackBlock Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::setRepeat false single undefined Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreStateMachine::pushState Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:44:53 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPushState Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreStateMachine::setRandom false Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreStateMachine::pushState Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPushState Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Setting Device type: Raspberry PI Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 02 13:44:54 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Completed loading Core Plugins Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Preparing to generate the ALSA configuration file Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Reading ALSA contributions from plugins. Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: VolumeController:: Volume=100 Mute =false Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreStateMachine::pushState Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPushState Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Asound.conf file written Sep 02 13:44:54 extremejukebox2 sudo[22574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 02 13:44:54 extremejukebox2 sudo[22574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:54 extremejukebox2 sudo[22574]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Output device has changed, restarting MPD Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Output device has changed, restarting Shairport Sync Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: ___________ START PLUGINS ___________ Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: ControllerMpd::onStart: Initializing MPD Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Creating MPD Configuration file Sep 02 13:44:54 extremejukebox2 sudo[22579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:44:54 extremejukebox2 sudo[22579]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:54 extremejukebox2 sudo[22579]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:54 extremejukebox2 sudo[22581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:44:54 extremejukebox2 sudo[22581]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: [1725284694655] CoreMusicLibrary::Adding element Server multimediali Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:54 extremejukebox2 systemd[1]: Stopping Music Player Daemon... Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:54 extremejukebox2 sudo[22589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:44:54 extremejukebox2 sudo[22589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:54 extremejukebox2 sudo[22589]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:54 extremejukebox2 sudo[22592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:44:54 extremejukebox2 sudo[22592]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: [1725284694832] CoreMusicLibrary::Adding element Last_100 Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: [1725284694835] CoreMusicLibrary::Adding element Webradio Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Initializing BBC Radios Sep 02 13:44:54 extremejukebox2 systemd[1]: mpd.service: Succeeded. Sep 02 13:44:54 extremejukebox2 systemd[1]: Stopped Music Player Daemon. Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Adding Calm Radio to Browse Sources Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: [1725284694964] CoreMusicLibrary::Adding element Calm Radio Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:54 extremejukebox2 volumio[22424]: Cannot find translation for source Calm Radio Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: Creating Spotify config file Sep 02 13:44:54 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:54 extremejukebox2 systemd[1]: Starting Music Player Daemon... Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Starting proxy server... Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [1725284695007] CoreMusicLibrary::Adding element YouTube2 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source Calm Radio Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source YouTube2 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [1725284695015] CoreMusicLibrary::Adding element YouTube Music Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source Calm Radio Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source YouTube2 Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source YouTube Music Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: AutoStart - onStart Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [now-playing] ConfigUpdater: config is up to date. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Loading i18n strings for locale it Sep 02 13:44:55 extremejukebox2 volumio[22424]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: FusionDsp - mixtype--------------------- Hardware Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [1725284695060] CoreMusicLibrary::Adding element 80s80s Radio Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source Calm Radio Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source YouTube2 Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source YouTube Music Sep 02 13:44:55 extremejukebox2 volumio[22424]: Cannot find translation for source 80s80s Radio Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Loading i18n strings for locale it Sep 02 13:44:55 extremejukebox2 volumio[22424]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Volumio Calling Home Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Proxy server started on port 44689 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Preparing to generate the ALSA configuration file Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [now-playing] App is listening on port 4004. Sep 02 13:44:55 extremejukebox2 volumio[22424]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Discovery: adding ab66e6ab-d3d4-46bf-95a1-b2a4e6e54ea9 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Discovery: Found device ExtremeJukebox_2 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Reading ALSA contributions from plugins. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: touch_display: Backlight interface detected. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: MPD Permissions set Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: MPD Permissions set Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Spotify config file written Sep 02 13:44:55 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: VolumeController:: Volume=100 Mute =false Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreStateMachine::pushState Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPushState Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Server discovery started Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Player finder started Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: touch_display: Raspberry Pi Foundation touch screen detected. Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE","S32_LE","SPECIAL","S24_3LE","DSD_U32_BE"] Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"extremejukebox2","dsdFormat":"DSD_U32_BE","card":"5","mixerType":"Hardware","mixer":"iFi (by AMR) HD USB Audio "} Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Starting Shairport Sync Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Starting Shairport Sync Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Starting Shairport Sync Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Asound.conf file unchanged, so no further update is needed Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Output device has changed, restarting MPD Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: Output device has changed, restarting Shairport Sync Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:55 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:56 extremejukebox2 sudo[22621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 02 13:44:56 extremejukebox2 sudo[22625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 02 13:44:56 extremejukebox2 sudo[22625]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 02 13:44:56 extremejukebox2 sudo[22621]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22619]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:56 extremejukebox2 systemd[1]: Reloading. Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: Starting Shairport Sync Sep 02 13:44:56 extremejukebox2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 13:44:56 extremejukebox2 systemd[1]: squeezelite.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 02 13:44:56 extremejukebox2 ntpd[778]: Soliciting pool server 85.199.214.99 Sep 02 13:44:56 extremejukebox2 sudo[22646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 13:44:56 extremejukebox2 sudo[22644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 13:44:56 extremejukebox2 sudo[22650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:44:56 extremejukebox2 sudo[22650]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22646]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22621]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:56 extremejukebox2 sudo[22648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 13:44:56 extremejukebox2 sudo[22648]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:44:56 extremejukebox2 sudo[22650]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:56 extremejukebox2 sudo[22644]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 sudo[22652]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 systemd[1]: Stopping go-librespot Daemon... Sep 02 13:44:56 extremejukebox2 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 13:44:56 extremejukebox2 systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 13:44:56 extremejukebox2 systemd[1]: Stopped go-librespot Daemon. Sep 02 13:44:56 extremejukebox2 systemd[1]: Reloading. Sep 02 13:44:56 extremejukebox2 volumio[22424]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: MPD Permissions set Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: touch_display: systemctl daemon-reload succeeded. Sep 02 13:44:56 extremejukebox2 sudo[22642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Sep 02 13:44:56 extremejukebox2 sudo[22642]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: Volumio called home Sep 02 13:44:56 extremejukebox2 sudo[22642]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:56 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Sep 02 13:44:57 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to 127.0.0.1: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: 5 Sep 02 13:44:57 extremejukebox2 volumio[22424]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 13:44:57 extremejukebox2 volumio[22424]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 02 13:44:57 extremejukebox2 volumio[22424]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: camilladsp spawned new process with pid 22718, instance 1, run: true Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: camilladsp service started and running in background, instance 1 Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:57 extremejukebox2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 13:44:57 extremejukebox2 volumio[22424]: cannot open device 'volumioHw': Device or resource busy Sep 02 13:44:57 extremejukebox2 volumio[22424]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 02 13:44:57 extremejukebox2 volumio[22424]: cannot open device 'volumioHw': Device or resource busy Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: FusionDsp loaded Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Adding Signal Path Element [object Object] Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Adding fusiondspeq DSP Signal Path Element Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: FusionDsp - ---- installed callbackRead Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 02 13:44:57 extremejukebox2 sudo[22667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 13:44:57 extremejukebox2 sudo[22667]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:57 extremejukebox2 sudo[22681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:44:57 extremejukebox2 sudo[22683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:44:57 extremejukebox2 sudo[22681]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:57 extremejukebox2 sudo[22683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:44:57 extremejukebox2 sudo[22683]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 sudo[22681]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 sudo[22619]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 02 13:44:57 extremejukebox2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 13:44:57 extremejukebox2 systemd[1]: Started go-librespot Daemon. Sep 02 13:44:57 extremejukebox2 sudo[22625]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 go-librespot[22732]: Librespot-go daemon starting... Sep 02 13:44:57 extremejukebox2 systemd[1]: shairport-sync.service: Succeeded. Sep 02 13:44:57 extremejukebox2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 13:44:57 extremejukebox2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 13:44:57 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:44:57Z" level=info msg="generated new device id: 60848bb524bc6c579abd7205a06376d21628390a" Sep 02 13:44:57 extremejukebox2 sudo[22644]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 sudo[22648]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 sudo[22646]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 sudo[22667]: pam_unix(sudo:session): session closed for user root Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Shairport-Sync Started Sep 02 13:44:57 extremejukebox2 volumio[22424]: Error adding Membership: Error: addMembership EINVAL Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Shairport-Sync Started Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Shairport-Sync Started Sep 02 13:44:57 extremejukebox2 volumio[22424]: info: Shairport-Sync Started Sep 02 13:44:58 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:44:58] [connect] Successful connection Sep 02 13:44:58 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:44:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725284698 101 Sep 02 13:44:58 extremejukebox2 volumio[22424]: 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: 6 Sep 02 13:44:58 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:59 extremejukebox2 sudo[22744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //undefined/\\\\DESKTOP-980IALB\\Musica /mnt/NAS/musica Sep 02 13:44:59 extremejukebox2 sudo[22744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: Listing playlists Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreStateMachine::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: Listing playlists Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreStateMachine::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: Listing playlists Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreStateMachine::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CorePlayQueue::getQueue Sep 02 13:44:59 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:00 extremejukebox2 volumio[22424]: info: go-librespot daemon successfully initialized Sep 02 13:45:00 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea803fda.b17dcb96 does not match aorg 0xea803fdc.b17d61c6 from server@93.44.146.104 xmt 0xea803fda.e96aceaa Sep 02 13:45:03 extremejukebox2 volumio[22424]: info: Initializing connection to go-librespot Websocket Sep 02 13:45:03 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:07 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea803fdf.b17905b5 does not match aorg 0xea803fe3.b17857c5 from server@85.199.214.99 xmt 0xea803fdf.cf3cff55 Sep 02 13:45:07 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea803fe1.b1792aaa does not match aorg 0xea803fe3.b17857c5 from server@85.199.214.99 xmt 0xea803fe1.bc7e5eed Sep 02 13:45:07 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:10 extremejukebox2 sudo[22685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 02 13:45:10 extremejukebox2 sudo[22685]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:10 extremejukebox2 sudo[22639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Sep 02 13:45:10 extremejukebox2 sudo[22639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:10 extremejukebox2 sudo[22639]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: File permissions for backlight brightness control set. Sep 02 13:45:10 extremejukebox2 sudo[22685]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: Upmpdcli Daemon Started Sep 02 13:45:10 extremejukebox2 sudo[22694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 02 13:45:10 extremejukebox2 sudo[22694]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:10 extremejukebox2 ntpd[778]: Soliciting pool server 95.110.254.234 Sep 02 13:45:10 extremejukebox2 sudo[22694]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: Volumio Kiosk started. Sep 02 13:45:10 extremejukebox2 sudo[22728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 02 13:45:10 extremejukebox2 sudo[22728]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:10 extremejukebox2 sudo[22728]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: X display number found: 0 Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 02 13:45:10 extremejukebox2 volumio[22424]: info: touch_display: X display number found: 0 Sep 02 13:45:11 extremejukebox2 volumio[22424]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 02 13:45:11 extremejukebox2 sudo[22704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Sep 02 13:45:11 extremejukebox2 sudo[22704]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:11 extremejukebox2 sudo[22704]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:11 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Sep 02 13:45:11 extremejukebox2 sudo[22815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Sep 02 13:45:11 extremejukebox2 sudo[22815]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:11 extremejukebox2 sudo[22815]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:11 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Sep 02 13:45:11 extremejukebox2 sudo[22818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 02 13:45:11 extremejukebox2 sudo[22818]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:11 extremejukebox2 systemd[1]: Reloading. Sep 02 13:45:11 extremejukebox2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 13:45:11 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:12 extremejukebox2 sudo[22818]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:12 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: AutoStart - Plugin is starting Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetQueue Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::getQueue Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CorePlayQueue::getQueue Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: AutoStart - start playing Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: AutoStart - start playing with no specific position Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioPlay Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::play index 0 Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::stop Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::play index undefined Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreStateMachine::startPlaybackTimer Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:45:15 extremejukebox2 volumio[22424]: verbose: ControllerMpd::clearAddPlayTracks USB/Daphile_Music/Blue Oyster Cult/1994 - Cult Classic/01. (Don't Fear) The Reaper (Remastered).flac Sep 02 13:45:15 extremejukebox2 volumio[22424]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 13:45:15 extremejukebox2 sudo[22836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:45:15 extremejukebox2 sudo[22836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:15 extremejukebox2 sudo[22836]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Sep 02 13:45:15 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:17 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumioGetState Sep 02 13:45:17 extremejukebox2 volumio[22424]: info: CorePlayQueue::getTrack 0 Sep 02 13:45:18 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:45:18Z" level=debug msg="fetched new accesspoints: [ap2-gew4.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gew4.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gew1.spotify.com:443 ap2-gae2.spotify.com:80]" Sep 02 13:45:18 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:45:18Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 02 13:45:18 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:45:18Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 02 13:45:18 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:45:18Z" level=debug msg="zeroconf server listening on port 43119" Sep 02 13:45:19 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:20 extremejukebox2 sudo[22637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 02 13:45:20 extremejukebox2 sudo[22637]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:20 extremejukebox2 sudo[22637]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:20 extremejukebox2 volumio[22424]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 02 13:45:23 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 02 13:45:25 extremejukebox2 volumio[22424]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Sep 02 13:45:25 extremejukebox2 volumio[22424]: error: Plugin music_service squeezelite_mc failed to complete 'onStart' in a timely fashion Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: ----- MyVolumio plugins startup ---- Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: ------------------------------------------- Sep 02 13:45:25 extremejukebox2 volumio[22424]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 02 13:45:27 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:31 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:35 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:39 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:40 extremejukebox2 sudo[22839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Sep 02 13:45:40 extremejukebox2 sudo[22839]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:40 extremejukebox2 systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Sep 02 13:45:40 extremejukebox2 systemd[1]: squeezelite.service: Succeeded. Sep 02 13:45:40 extremejukebox2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Sep 02 13:45:40 extremejukebox2 sudo[22839]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:40 extremejukebox2 volumio[22424]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:45:42 extremejukebox2 sudo[22744]: pam_unix(sudo:session): session closed for user root Sep 02 13:45:42 extremejukebox2 volumio[22424]: info: Cannot mount NAS musica at system boot, trial number 2 ,retrying in 5 seconds Sep 02 13:45:42 extremejukebox2 volumio[22424]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 02 13:45:42 extremejukebox2 go-librespot[22732]: time="2024-09-02T13:45:42Z" level=debug msg="new websocket client" Sep 02 13:45:43 extremejukebox2 volumio[22424]: info: Connection to go-librespot Websocket established Sep 02 13:45:43 extremejukebox2 volumio[22424]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 02 13:45:43 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:45 extremejukebox2 volumio[22424]: error: MyVolumio Plugin failed to start in a timely fashion Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: BOOT COMPLETED Sep 02 13:45:45 extremejukebox2 volumio[22424]: [Metrics] CommandRouter: 62s 435.12ms Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: CoreCommandRouter::volumiosetStartupVolume Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: FusionDsp - ---- read samplerate, raw: Sep 02 13:45:45 extremejukebox2 volumio[22424]: error: FusionDsp - invalid sample rate Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: camilladsp stopping service pid 22718... Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: camilladsp service terminated, instance 1 Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: camilladsp service started and running in background, instance 1 Sep 02 13:45:45 extremejukebox2 volumio[22424]: info: camilladsp spawned new process with pid 22869, instance 1, run: true Sep 02 13:45:46 extremejukebox2 volumio[22424]: info: Getting Spotify volume Sep 02 13:45:46 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 02 13:45:46 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 13:45:46 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 02 13:45:47 extremejukebox2 volumio[22424]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:45:47 extremejukebox2 volumio[22424]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:45:47 extremejukebox2 volumio[22424]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Sep 02 13:45:47 extremejukebox2 volumio[22424]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Sep 02 13:45:47 extremejukebox2 volumio[22424]: errno: -3007, Sep 02 13:45:47 extremejukebox2 volumio[22424]: code: 'ENOTFOUND', Sep 02 13:45:47 extremejukebox2 volumio[22424]: syscall: 'getaddrinfo', Sep 02 13:45:47 extremejukebox2 volumio[22424]: hostname: 'lastfm.freetls.fastly.net' Sep 02 13:45:47 extremejukebox2 volumio[22424]: } Sep 02 13:45:47 extremejukebox2 volumio[22424]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:45:53 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:45:53] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 02 13:45:57 extremejukebox2 sudo[22882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //undefined/\\\\DESKTOP-980IALB\\Musica /mnt/NAS/musica Sep 02 13:45:57 extremejukebox2 sudo[22882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:45:58 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:45:58] [connect] Successful connection Sep 02 13:46:02 extremejukebox2 sudo[22617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 02 13:46:02 extremejukebox2 sudo[22617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:46:02 extremejukebox2 sudo[22617]: pam_unix(sudo:session): session closed for user root Sep 02 13:46:05 extremejukebox2 sudo[22860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:46:05 extremejukebox2 sudo[22860]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:46:05 extremejukebox2 sudo[22860]: pam_unix(sudo:session): session closed for user root Sep 02 13:46:08 extremejukebox2 sudo[22882]: pam_unix(sudo:session): session closed for user root Sep 02 13:46:08 extremejukebox2 sudo[22604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 13:46:08 extremejukebox2 sudo[22604]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:46:08 extremejukebox2 sudo[22604]: pam_unix(sudo:session): session closed for user root Sep 02 13:46:08 extremejukebox2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 13:46:08 extremejukebox2 systemd[1]: mpd.service: Succeeded. Sep 02 13:46:08 extremejukebox2 systemd[1]: Stopped Music Player Daemon. Sep 02 13:46:08 extremejukebox2 systemd[1]: Starting Music Player Daemon... Sep 02 13:46:10 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:46:10] [connect] Successful connection Sep 02 13:46:14 extremejukebox2 sudo[22892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 13:44 Sep 02 13:46:14 extremejukebox2 sudo[22892]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"