-- Logs begin at Sat 2024-03-16 15:13:08 CET, end at Sat 2024-10-05 17:54:55 CEST. -- Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.64625d96 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xeaabdf6b.14a094b2 Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.645f70a8 does not match aorg 0000000000.00000000 from server@93.44.243.48 xmt 0xeaabdf6b.1532963f Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.646eed67 does not match aorg 0000000000.00000000 from server@188.213.165.209 xmt 0xeaabdf6b.15421bc1 Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.646a183a does not match aorg 0000000000.00000000 from server@37.247.53.178 xmt 0xeaabdf6b.162ead9c Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.64675b2b does not match aorg 0000000000.00000000 from server@95.110.254.234 xmt 0xeaabdf6b.158c87c4 Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.6458735b does not match aorg 0000000000.00000000 from server@93.94.88.50 xmt 0xeaabdf6b.169e7dd7 Oct 05 17:53:15 volumio ntpd[906]: receive: Unexpected origin timestamp 0xe9a02797.646c6e39 does not match aorg 0000000000.00000000 from server@212.45.144.88 xmt 0xeaabdf6b.1bbba26a Oct 05 17:53:15 volumio systemd[1]: Starting Daily apt download activities... Oct 05 17:53:17 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 05 17:53:18 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:53:18 volumio volumio[1006]: info: ----- Volumio3 ---- Oct 05 17:53:18 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:53:18 volumio volumio[1006]: info: ----- System startup ---- Oct 05 17:53:18 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:53:19 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:19 volumio systemd[1]: apt-daily.service: Succeeded. Oct 05 17:53:19 volumio systemd[1]: Started Daily apt download activities. Oct 05 17:53:19 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 05 17:53:19 volumio volumio-remote-updater[568]: [2024-10-05 17:53:19] [connect] Successful connection Oct 05 17:53:20 volumio volumio[1006]: info: MYVOLUMIO Environment detected Oct 05 17:53:20 volumio volumio[1006]: info: Plugin folders cleanup Oct 05 17:53:20 volumio volumio[1006]: info: Scanning into folder /volumio/app/plugins/ Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category audio_interface Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category miscellanea Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category music_service Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category plugins.json Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category system_controller Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category user_interface Oct 05 17:53:20 volumio volumio[1006]: info: Scanning into folder /data/plugins/ Oct 05 17:53:20 volumio volumio[1006]: info: Scanning category audio_interface Oct 05 17:53:20 volumio volumio[1006]: info: Plugin folders cleanup completed Oct 05 17:53:20 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:53:20 volumio volumio[1006]: info: ----- Core plugins startup ---- Oct 05 17:53:20 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:53:20 volumio volumio[1006]: info: Loading plugins from folder /volumio/app/plugins/ Oct 05 17:53:20 volumio volumio[1006]: info: Adding plugin upnp to MyMusic Plugins Oct 05 17:53:20 volumio volumio[1006]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 05 17:53:20 volumio volumio[1006]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 05 17:53:20 volumio volumio[1006]: info: Loading plugins from folder /data/plugins/ Oct 05 17:53:20 volumio volumio[1006]: info: Loading plugin "system"... Oct 05 17:53:20 volumio volumio[1006]: info: Loading plugin "appearance"... Oct 05 17:53:21 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 05 17:53:21 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 05 17:53:23 volumio volumio[1006]: info: Loading plugin "network"... Oct 05 17:53:23 volumio volumio[1006]: info: Refreshing Cached IP Addresses Oct 05 17:53:23 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 17:53:23 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:23 volumio volumio[1006]: info: Loading plugin "services"... Oct 05 17:53:23 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 17:53:23 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:23 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:23 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:23 volumio volumio[1006]: info: Loading plugin "alsa_controller"... Oct 05 17:53:23 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 05 17:53:23 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:23 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 17:53:23 volumio volumio[1006]: info: Loading plugin "wizard"... Oct 05 17:53:23 volumio volumio[1006]: info: Loading plugin "networkfs"... Oct 05 17:53:23 volumio volumio[1006]: info: Starting Udev Watcher for removable devices Oct 05 17:53:23 volumio volumio[1006]: info: Ignoring mount for partition: boot Oct 05 17:53:23 volumio volumio[1006]: info: Ignoring mount for partition: volumio Oct 05 17:53:23 volumio volumio[1006]: info: Ignoring mount for partition: volumio_data Oct 05 17:53:23 volumio volumio[1006]: info: Mounting Device 889D-D223 Oct 05 17:53:23 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/889D-D223 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 05 17:53:23 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:23 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 05 17:53:23 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:23 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 05 17:53:23 volumio volumio[1006]: info: Mounting Device KINGSTON Oct 05 17:53:23 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/KINGSTON -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 05 17:53:23 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 05 17:53:24 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 05 17:53:24 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:24 volumio volumio[1006]: info: Mounting Device UEFI_NTFS Oct 05 17:53:24 volumio sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/UEFI_NTFS -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 05 17:53:24 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:24 volumio kernel: FAT-fs (sda2): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 05 17:53:24 volumio kernel: FAT-fs (sda2): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 05 17:53:24 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:24 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 17:53:24 volumio volumio[1006]: info: Loading plugin "volumio_command_line_client"... Oct 05 17:53:24 volumio volumio[1006]: info: Loading plugin "upnp"... Oct 05 17:53:24 volumio volumio[1006]: info: [1728143604256] Starting Upmpd Daemon Oct 05 17:53:24 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 17:53:24 volumio volumio[1006]: info: Loading plugin "my_music"... Oct 05 17:53:24 volumio volumio[1006]: info: Loading plugin "mpd"... Oct 05 17:53:24 volumio volumio[1006]: info: Loading plugin "upnp_browser"... Oct 05 17:53:25 volumio volumio[1006]: info: Loading plugin "alarm-clock"... Oct 05 17:53:25 volumio volumio[1006]: info: Loading plugin "airplay_emulation"... Oct 05 17:53:25 volumio volumio[1006]: info: Starting Shairport Sync Oct 05 17:53:25 volumio volumio[1006]: info: Loading plugin "last_100"... Oct 05 17:53:25 volumio volumio[1006]: info: Loading plugin "webradio"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "i2s_dacs"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "volumiodiscovery"... Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 17:53:26 volumio node[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** For more information see Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 17:53:26 volumio volumio[1006]: *** WARNING *** For more information see Oct 05 17:53:26 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 17:53:26 volumio node[1006]: *** WARNING *** For more information see Oct 05 17:53:26 volumio node[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 05 17:53:26 volumio node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 05 17:53:26 volumio node[1006]: *** WARNING *** For more information see Oct 05 17:53:26 volumio volumio[1006]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 05 17:53:26 volumio volumio[1006]: info: Discovery: Started advertising with name: Volumio Oct 05 17:53:26 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "outputs"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "albumart"... Oct 05 17:53:26 volumio volumio[1006]: info: Plugin example_plugin is not enabled Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "inputs"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "updater_comm"... Oct 05 17:53:26 volumio volumio[1006]: info: Plugin mpdemulation is not enabled Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "rest_api"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "websocket"... Oct 05 17:53:26 volumio volumio[1006]: info: Loading plugin "fusiondsp"... Oct 05 17:53:26 volumio volumio[1006]: Forking 3 albumart workers Oct 05 17:53:27 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:53:27 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:53:27 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:53:27 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:53:27 volumio dhcpcd[937]: eth0: deleting route to 192.168.1.0/24 Oct 05 17:53:27 volumio dhcpcd[937]: eth0: deleting default route via 192.168.1.254 Oct 05 17:53:27 volumio avahi-daemon[562]: Withdrawing address record for 192.168.1.50 on eth0. Oct 05 17:53:27 volumio avahi-daemon[562]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.50. Oct 05 17:53:27 volumio avahi-daemon[562]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 05 17:53:27 volumio dhcpcd[835]: eth0: deleting route to 192.168.1.0/24 Oct 05 17:53:27 volumio dhcpcd[835]: eth0: deleting default route via 192.168.1.254 Oct 05 17:53:27 volumio volumio[1006]: info: Applying required configuration parameters for plugin fusiondsp Oct 05 17:53:27 volumio ntpd[906]: ntpd exiting on signal 15 (Terminated) Oct 05 17:53:27 volumio ntpd[906]: 10.42.4.1 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 82.142.168.18 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 212.6.50.243 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 188.213.165.209 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 212.45.144.88 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 37.247.53.178 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 95.110.254.234 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 162.159.200.123 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 89.46.74.148 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 93.94.88.51 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 162.159.200.1 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 85.199.214.99 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 93.44.243.48 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 185.19.184.35 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 31.14.133.122 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio ntpd[906]: 93.94.88.50 local addr 192.168.1.50 -> Oct 05 17:53:27 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:53:27 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:53:27 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:53:27 volumio systemd[1]: Starting Network Time Service... Oct 05 17:53:27 volumio ntpd[1283]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:53:27 volumio ntpd[1283]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:53:27 volumio ntpd[1289]: proto: precision = 2.222 usec (-19) Oct 05 17:53:27 volumio systemd[1]: Started Network Time Service. Oct 05 17:53:27 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:53:27 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:53:27 volumio ntpd[1289]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:53:27 volumio ntpd[1289]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:53:27 volumio ntpd[1289]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:53:27 volumio ntpd[1289]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:53:27 volumio ntpd[1289]: Listening on routing socket on fd #19 for interface updates Oct 05 17:53:27 volumio ntpd[1289]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 05 17:53:27 volumio ntpd[1289]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 05 17:53:28 volumio volumio[1006]: info: Loading i18n strings for locale it Oct 05 17:53:28 volumio volumio[1006]: Updating browse sources language Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 17:53:28 volumio volumio[1006]: Starting albumart workers Oct 05 17:53:28 volumio volumio[1006]: Starting albumart workers Oct 05 17:53:28 volumio volumio[1006]: Starting albumart workers Oct 05 17:53:28 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:28 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::initPlayerControls Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 17:53:28 volumio nmbd[657]: [2024/10/05 17:53:28.624633, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 05 17:53:28 volumio nmbd[657]: Packet send failed to 192.168.1.255(137) ERRNO=Network is unreachable Oct 05 17:53:28 volumio nmbd[657]: [2024/10/05 17:53:28.625069, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 05 17:53:28 volumio nmbd[657]: retransmit_or_expire_response_records: Failed to resend packet id 30025 to IP 192.168.1.255 on subnet 192.168.1.50 Oct 05 17:53:28 volumio volumio[1006]: Express server listening on port 3000 Oct 05 17:53:28 volumio volumio[1006]: [Metrics] WebUI: 11s 97.94ms Oct 05 17:53:28 volumio volumio[1006]: info: CoreStateMachine::resetVolumioState Oct 05 17:53:28 volumio volumio[1006]: info: CoreStateMachine::getcurrentVolume Oct 05 17:53:28 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 17:53:28 volumio ntpd[1289]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:29 volumio volumio[1006]: info: Reloading queue from file Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::setRepeat null single undefined Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::pushState Oct 05 17:53:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::setRandom null Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::pushState Oct 05 17:53:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 05 17:53:29 volumio volumio[1006]: info: Setting Device type: Raspberry PI Oct 05 17:53:29 volumio volumio[1006]: info: VolumeController:: Volume=100 Mute =false Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::pushState Oct 05 17:53:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::updateTrackBlock Oct 05 17:53:29 volumio volumio[1006]: info: CorePlayQueue::getTrackBlock Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 17:53:29 volumio volumio[1006]: info: Discovery: A device disappeared from network Oct 05 17:53:29 volumio volumio[1006]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Oct 05 17:53:29 volumio volumio[1006]: info: VolumeController:: Volume=100 Mute =false Oct 05 17:53:29 volumio volumio[1006]: info: CoreStateMachine::pushState Oct 05 17:53:29 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:29 volumio volumio[1006]: info: CoreCommandRouter::volumioPushState Oct 05 17:53:29 volumio nmbd[657]: [2024/10/05 17:53:29.626594, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 05 17:53:29 volumio nmbd[657]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Oct 05 17:53:29 volumio ntpd[1289]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 05 17:53:30 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:53:30 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:53:30 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:53:30 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:53:30 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:53:30 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:53:30 volumio dhcpcd[835]: eth0: soliciting a DHCP lease Oct 05 17:53:30 volumio dhcpcd[937]: eth0: soliciting a DHCP lease Oct 05 17:53:30 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:53:30 volumio ntpd[1289]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 05 17:53:31 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:53:31 volumio ntpd[1289]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 05 17:53:33 volumio dhcpcd[835]: eth0: offered 192.168.1.50 from 192.168.1.254 Oct 05 17:53:33 volumio dhcpcd[835]: eth0: probing address 192.168.1.50/24 Oct 05 17:53:34 volumio volumio-remote-updater[568]: [2024-10-05 17:53:34] [connect] Successful connection Oct 05 17:53:34 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 17:53:34 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:34 volumio volumio-remote-updater[568]: [2024-10-05 17:53:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728143614 101 Oct 05 17:53:34 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:34 volumio volumio[1006]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Oct 05 17:53:34 volumio sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 17:53:34 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:34 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:34 volumio volumio[1006]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 05 17:53:34 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 05 17:53:34 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:34 volumio dhcpcd[937]: eth0: offered 192.168.1.50 from 192.168.1.254 Oct 05 17:53:34 volumio dhcpcd[937]: eth0: probing address 192.168.1.50/24 Oct 05 17:53:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 05 17:53:34 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:34 volumio volumio[1006]: info: Upmpdcli Daemon Started Oct 05 17:53:34 volumio volumio[1006]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 05 17:53:35 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:38 volumio volumio[1006]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 05 17:53:38 volumio dhcpcd[835]: eth0: leased 192.168.1.50 for 86400 seconds Oct 05 17:53:38 volumio dhcpcd[835]: eth0: adding route to 192.168.1.0/24 Oct 05 17:53:38 volumio avahi-daemon[562]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.50. Oct 05 17:53:38 volumio dhcpcd[835]: eth0: adding default route via 192.168.1.254 Oct 05 17:53:38 volumio avahi-daemon[562]: New relevant interface eth0.IPv4 for mDNS. Oct 05 17:53:38 volumio avahi-daemon[562]: Registering new address record for 192.168.1.50 on eth0.IPv4. Oct 05 17:53:38 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:53:38 volumio ntpd[1289]: ntpd exiting on signal 15 (Terminated) Oct 05 17:53:38 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:53:38 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:53:38 volumio systemd[1]: Starting Network Time Service... Oct 05 17:53:39 volumio ntpd[1372]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:53:39 volumio ntpd[1372]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:53:39 volumio systemd[1]: Started Network Time Service. Oct 05 17:53:39 volumio ntpd[1380]: proto: precision = 2.148 usec (-19) Oct 05 17:53:39 volumio ntpd[1380]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:53:39 volumio ntpd[1380]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:53:39 volumio ntpd[1380]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:53:39 volumio ntpd[1380]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:53:39 volumio ntpd[1380]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:53:39 volumio ntpd[1380]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:53:39 volumio ntpd[1380]: Listen normally on 3 eth0 192.168.1.50:123 Oct 05 17:53:39 volumio ntpd[1380]: Listening on routing socket on fd #20 for interface updates Oct 05 17:53:39 volumio ntpd[1380]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:39 volumio ntpd[1380]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:39 volumio dhcpcd[937]: eth0: leased 192.168.1.50 for 86400 seconds Oct 05 17:53:39 volumio dhcpcd[937]: eth0: adding route to 192.168.1.0/24 Oct 05 17:53:39 volumio dhcpcd[937]: eth0: adding default route via 192.168.1.254 Oct 05 17:53:39 volumio volumio[1006]: info: Discovery: adding 14615318-8f95-4a8e-b33a-9943a2fc036f Oct 05 17:53:39 volumio volumio[1006]: info: Discovery: Found device Volumio Oct 05 17:53:39 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:53:39 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:40 volumio ntpd[1380]: Soliciting pool server 212.6.50.243 Oct 05 17:53:41 volumio ntpd[1380]: Soliciting pool server 188.213.165.209 Oct 05 17:53:41 volumio ntpd[1380]: Soliciting pool server 212.45.144.88 Oct 05 17:53:42 volumio ntpd[1380]: Soliciting pool server 37.247.53.178 Oct 05 17:53:42 volumio ntpd[1380]: Soliciting pool server 89.46.74.148 Oct 05 17:53:42 volumio ntpd[1380]: Soliciting pool server 162.159.200.123 Oct 05 17:53:43 volumio ntpd[1380]: Soliciting pool server 93.94.88.50 Oct 05 17:53:43 volumio ntpd[1380]: Soliciting pool server 85.199.214.99 Oct 05 17:53:43 volumio ntpd[1380]: Soliciting pool server 95.110.254.234 Oct 05 17:53:43 volumio ntpd[1380]: Soliciting pool server 185.19.184.35 Oct 05 17:53:43 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:44 volumio ntpd[1380]: Soliciting pool server 162.159.200.1 Oct 05 17:53:44 volumio ntpd[1380]: Soliciting pool server 93.44.243.48 Oct 05 17:53:44 volumio volumio[1006]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 05 17:53:45 volumio ntpd[1380]: Soliciting pool server 93.94.88.51 Oct 05 17:53:45 volumio ntpd[1380]: Soliciting pool server 93.94.88.51 Oct 05 17:53:46 volumio ntpd[1380]: Soliciting pool server 31.14.133.122 Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:53:47 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:53:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:53:47 volumio volumio[1006]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.58 UA: Dart/3.4 (dart:io) Total Clients: 2 Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:53:47 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:53:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:53:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 05 17:53:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 05 17:53:50 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:53:50 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:53:50 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:53:50 volumio ntpd[1380]: ntpd exiting on signal 15 (Terminated) Oct 05 17:53:50 volumio ntpd[1380]: 10.42.4.1 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 82.142.168.18 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 212.6.50.243 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 188.213.165.209 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 212.45.144.88 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 162.159.200.123 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 37.247.53.178 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 89.46.74.148 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:53:50 volumio ntpd[1380]: 95.110.254.234 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 93.94.88.50 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 185.19.184.35 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 85.199.214.99 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:53:50 volumio ntpd[1380]: 162.159.200.1 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 93.44.243.48 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 93.94.88.51 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio ntpd[1380]: 31.14.133.122 local addr 192.168.1.50 -> Oct 05 17:53:50 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:53:50 volumio systemd[1]: Starting Network Time Service... Oct 05 17:53:50 volumio ntpd[1501]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:53:50 volumio ntpd[1501]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:53:50 volumio systemd[1]: Started Network Time Service. Oct 05 17:53:50 volumio ntpd[1507]: proto: precision = 2.148 usec (-19) Oct 05 17:53:50 volumio ntpd[1507]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:53:50 volumio ntpd[1507]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:53:50 volumio ntpd[1507]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:53:50 volumio ntpd[1507]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:53:50 volumio ntpd[1507]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:53:50 volumio ntpd[1507]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:53:50 volumio ntpd[1507]: Listening on routing socket on fd #19 for interface updates Oct 05 17:53:50 volumio ntpd[1507]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:50 volumio ntpd[1507]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:50 volumio dhcpcd[937]: eth0: deleting route to 192.168.1.0/24 Oct 05 17:53:50 volumio dhcpcd[937]: eth0: deleting default route via 192.168.1.254 Oct 05 17:53:50 volumio avahi-daemon[562]: Withdrawing address record for 192.168.1.50 on eth0. Oct 05 17:53:50 volumio avahi-daemon[562]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.50. Oct 05 17:53:50 volumio volumio[1006]: info: Discovery: A device disappeared from network Oct 05 17:53:50 volumio volumio[1006]: info: Discovery: Device volumio disappeared from network Oct 05 17:53:50 volumio avahi-daemon[562]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 05 17:53:50 volumio dhcpcd[835]: eth0: deleting route to 192.168.1.0/24 Oct 05 17:53:50 volumio dhcpcd[835]: eth0: deleting default route via 192.168.1.254 Oct 05 17:53:50 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:53:50 volumio ntpd[1507]: ntpd exiting on signal 15 (Terminated) Oct 05 17:53:50 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:53:50 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:53:50 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:53:50 volumio systemd[1]: Starting Network Time Service... Oct 05 17:53:50 volumio ntpd[1549]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:53:50 volumio ntpd[1549]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:53:50 volumio systemd[1]: Started Network Time Service. Oct 05 17:53:50 volumio ntpd[1555]: proto: precision = 2.074 usec (-19) Oct 05 17:53:50 volumio ntpd[1555]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:53:50 volumio ntpd[1555]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:53:50 volumio ntpd[1555]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:53:50 volumio ntpd[1555]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:53:50 volumio ntpd[1555]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:53:50 volumio ntpd[1555]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:53:50 volumio ntpd[1555]: Listening on routing socket on fd #19 for interface updates Oct 05 17:53:50 volumio ntpd[1555]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:50 volumio ntpd[1555]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:51 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:51 volumio ntpd[1555]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 05 17:53:52 volumio ntpd[1555]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 05 17:53:53 volumio ntpd[1555]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 05 17:53:54 volumio volumio[1006]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Oct 05 17:53:54 volumio ntpd[1555]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 05 17:53:56 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:53:56 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:53:56 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:53:56 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:53:56 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:53:56 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:53:56 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:53:56 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:53:56 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:53:57 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:53:57 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:53:57 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:53:57 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:53:57 volumio ntpd[1555]: ntpd exiting on signal 15 (Terminated) Oct 05 17:53:57 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:53:57 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:53:57 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:53:57 volumio systemd[1]: Starting Network Time Service... Oct 05 17:53:57 volumio ntpd[1609]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:53:57 volumio ntpd[1609]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:53:57 volumio systemd[1]: Started Network Time Service. Oct 05 17:53:57 volumio ntpd[1615]: proto: precision = 2.203 usec (-19) Oct 05 17:53:57 volumio ntpd[1615]: line 0 column 0 syntax error, unexpected $end Oct 05 17:53:57 volumio ntpd[1615]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:53:57 volumio ntpd[1615]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:53:57 volumio ntpd[1615]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:53:57 volumio ntpd[1615]: Listening on routing socket on fd #19 for interface updates Oct 05 17:53:57 volumio ntpd[1615]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:57 volumio ntpd[1615]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:53:57 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:53:57 volumio volumio[1006]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Oct 05 17:53:57 volumio volumio[1006]: info: Preparing to generate the ALSA configuration file Oct 05 17:53:58 volumio volumio[1006]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 05 17:53:58 volumio volumio[1006]: info: Reading ALSA contributions from plugins. Oct 05 17:53:58 volumio volumio[1006]: info: Asound.conf file unchanged, so no further update is needed Oct 05 17:53:58 volumio volumio[1006]: info: Output device has changed, restarting MPD Oct 05 17:53:58 volumio volumio[1006]: info: Output device has changed, restarting Shairport Sync Oct 05 17:53:58 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:58 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:58 volumio sudo[1620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 17:53:58 volumio sudo[1620]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:58 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 17:53:58 volumio volumio[1006]: info: ___________ START PLUGINS ___________ Oct 05 17:53:58 volumio volumio[1006]: info: ControllerMpd::onStart: Initializing MPD Oct 05 17:53:58 volumio volumio[1006]: info: Creating MPD Configuration file Oct 05 17:53:58 volumio systemd[1]: Listening on mpd.socket. Oct 05 17:53:58 volumio systemd[1]: Starting Music Player Daemon... Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 17:53:58 volumio sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 17:53:58 volumio volumio[1006]: info: [1728143638245] CoreMusicLibrary::Adding element Server multimediali Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 17:53:58 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:58 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:58 volumio sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 17:53:58 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:58 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 05 17:53:58 volumio systemd[1]: mpd.service: Succeeded. Oct 05 17:53:58 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 17:53:58 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:58 volumio systemd[1]: Starting Music Player Daemon... Oct 05 17:53:58 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 17:53:58 volumio volumio[1006]: info: [1728143638452] CoreMusicLibrary::Adding element Last_100 Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 05 17:53:58 volumio volumio[1006]: info: [1728143638457] CoreMusicLibrary::Adding element Webradio Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 17:53:58 volumio volumio[1006]: info: Loading i18n strings for locale it Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: FusionDsp - mixtype--------------------- Hardware Oct 05 17:53:58 volumio volumio[1006]: info: Volumio Calling Home Oct 05 17:53:58 volumio volumio[1006]: info: Preparing to generate the ALSA configuration file Oct 05 17:53:58 volumio volumio[1006]: Unhandled rejection Error: No sockets available, cannot start. Oct 05 17:53:58 volumio volumio[1006]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 05 17:53:58 volumio volumio[1006]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 05 17:53:58 volumio volumio[1006]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 05 17:53:58 volumio volumio[1006]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 05 17:53:58 volumio volumio[1006]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 05 17:53:58 volumio volumio[1006]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 05 17:53:58 volumio volumio[1006]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 05 17:53:58 volumio volumio[1006]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 05 17:53:58 volumio volumio[1006]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 05 17:53:58 volumio volumio[1006]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 05 17:53:58 volumio volumio[1006]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 05 17:53:58 volumio volumio[1006]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 05 17:53:58 volumio volumio[1006]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 05 17:53:58 volumio volumio[1006]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 05 17:53:58 volumio volumio[1006]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 05 17:53:58 volumio volumio[1006]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 05 17:53:58 volumio volumio[1006]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 05 17:53:58 volumio volumio[1006]: info: Reading ALSA contributions from plugins. Oct 05 17:53:58 volumio volumio[1006]: info: MPD Permissions set Oct 05 17:53:58 volumio volumio[1006]: info: MPD Permissions set Oct 05 17:53:58 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:53:58 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:58 volumio volumio[1006]: info: Starting Shairport Sync Oct 05 17:53:58 volumio volumio[1006]: info: Starting Shairport Sync Oct 05 17:53:58 volumio volumio[1006]: info: Starting Shairport Sync Oct 05 17:53:58 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 17:53:58 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:58 volumio sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 17:53:58 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 17:53:59 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 17:53:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 17:53:59 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:59 volumio volumio[1006]: info: Asound.conf file unchanged, so no further update is needed Oct 05 17:53:59 volumio volumio[1006]: info: Output device has changed, restarting MPD Oct 05 17:53:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 17:53:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 05 17:53:59 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 17:53:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 17:53:59 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:59 volumio volumio[1006]: info: Output device has changed, restarting Shairport Sync Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:59 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:59 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:59 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 17:53:59 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:59 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 05 17:53:59 volumio systemd[1]: mpd.service: Succeeded. Oct 05 17:53:59 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 17:53:59 volumio volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 17:53:59 volumio systemd[1]: Starting Music Player Daemon... Oct 05 17:53:59 volumio volumio[1006]: info: MPD Permissions set Oct 05 17:53:59 volumio volumio[1006]: info: Shairport-Sync Started Oct 05 17:53:59 volumio volumio[1006]: Error adding Membership: Error: addMembership EINVAL Oct 05 17:53:59 volumio volumio[1006]: info: Shairport-Sync Started Oct 05 17:53:59 volumio volumio[1006]: info: Shairport-Sync Started Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:53:59 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:53:59 volumio volumio[1006]: info: Starting Shairport Sync Oct 05 17:53:59 volumio sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 17:53:59 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:53:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 17:53:59 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 17:53:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 17:53:59 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Oct 05 17:53:59 volumio volumio[1006]: info: Shairport-Sync Started Oct 05 17:54:00 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:54:00 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:54:00 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:54:00 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:54:00 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:54:00 volumio volumio[1006]: 0+0 records in Oct 05 17:54:00 volumio volumio[1006]: 0+0 records out Oct 05 17:54:00 volumio volumio[1006]: 0 bytes copied, 0.00025413 s, 0.0 kB/s Oct 05 17:54:00 volumio volumio[1006]: info: camilladsp spawned new process with pid 1695, instance 1, run: true Oct 05 17:54:00 volumio volumio[1006]: info: camilladsp service started and running in background, instance 1 Oct 05 17:54:00 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp loaded Oct 05 17:54:00 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:00 volumio sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 05 17:54:00 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 05 17:54:00 volumio volumio[1006]: info: Adding Signal Path Element [object Object] Oct 05 17:54:00 volumio volumio[1006]: info: Adding fusiondspeq DSP Signal Path Element Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - ---- installed callbackRead Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 05 17:54:00 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:54:00 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 05 17:54:00 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - eq1: Oct 05 17:54:00 volumio volumio[1006]: type: Biquad Oct 05 17:54:00 volumio volumio[1006]: parameters: Oct 05 17:54:00 volumio volumio[1006]: type: Lowshelf Oct 05 17:54:00 volumio volumio[1006]: freq: 185 Oct 05 17:54:00 volumio volumio[1006]: q: 0.82 Oct 05 17:54:00 volumio volumio[1006]: gain: 0 Oct 05 17:54:00 volumio volumio[1006]: eq2: Oct 05 17:54:00 volumio volumio[1006]: type: Biquad Oct 05 17:54:00 volumio volumio[1006]: parameters: Oct 05 17:54:00 volumio volumio[1006]: type: Peaking Oct 05 17:54:00 volumio volumio[1006]: freq: 1300 Oct 05 17:54:00 volumio volumio[1006]: q: 0.4 Oct 05 17:54:00 volumio volumio[1006]: gain: 0 Oct 05 17:54:00 volumio volumio[1006]: eq3: Oct 05 17:54:00 volumio volumio[1006]: type: Biquad Oct 05 17:54:00 volumio volumio[1006]: parameters: Oct 05 17:54:00 volumio volumio[1006]: type: Highshelf Oct 05 17:54:00 volumio volumio[1006]: freq: 5500 Oct 05 17:54:00 volumio volumio[1006]: q: 0.82 Oct 05 17:54:00 volumio volumio[1006]: gain: 0 Oct 05 17:54:00 volumio systemd[1]: Started FusionDsp Daemon. Oct 05 17:54:00 volumio volumio[1006]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 05 17:54:00 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:00 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:54:00 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:54:01 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:01 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:54:01 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:54:01 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:54:01 volumio mpd[1674]: Oct 05 17:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 17:54:01 volumio systemd[1]: Started Music Player Daemon. Oct 05 17:54:01 volumio sudo[1620]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:01 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:01 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:01 volumio volumio[1006]: info: Completed starting Core Plugins Oct 05 17:54:01 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:54:01 volumio volumio[1006]: info: ----- MyVolumio plugins startup ---- Oct 05 17:54:01 volumio volumio[1006]: info: ------------------------------------------- Oct 05 17:54:01 volumio volumio[1006]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 05 17:54:01 volumio ntpd[1615]: ntpd exiting on signal 15 (Terminated) Oct 05 17:54:01 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:54:01 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:54:01 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:54:01 volumio systemd[1]: Starting Network Time Service... Oct 05 17:54:01 volumio systemd[1]: ntp.service: Control process exited, code=killed, status=15/TERM Oct 05 17:54:01 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:54:01 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:54:01 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio volumio[1006]: error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio volumio[1006]: error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio volumio[1006]: info: MPD running with PID1674 Oct 05 17:54:01 volumio volumio[1006]: ,establishing connection Oct 05 17:54:01 volumio volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio volumio[1006]: error: The expression evaluated to a falsy value: Oct 05 17:54:01 volumio volumio[1006]: assert.ok(self.idling) Oct 05 17:54:01 volumio systemd[1]: Starting Network Time Service... Oct 05 17:54:01 volumio volumio[1006]: error: updateQueue error: null Oct 05 17:54:02 volumio ntpd[1751]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:54:02 volumio ntpd[1751]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:54:02 volumio systemd[1]: Started Network Time Service. Oct 05 17:54:02 volumio ntpd[1757]: proto: precision = 2.055 usec (-19) Oct 05 17:54:02 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:54:02 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:54:02 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:54:02 volumio ntpd[1757]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:54:02 volumio ntpd[1757]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:54:02 volumio ntpd[1757]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:54:02 volumio ntpd[1757]: Listening on routing socket on fd #19 for interface updates Oct 05 17:54:02 volumio ntpd[1757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:54:02 volumio ntpd[1757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:54:02 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:54:03 volumio ntpd[1757]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 05 17:54:04 volumio ntpd[1757]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 05 17:54:04 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:54:04 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:54:04 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:54:04 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:54:04 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:54:04 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:54:05 volumio ntpd[1757]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 05 17:54:05 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:54:05 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:54:05 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:05 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:05 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:54:05 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:54:05 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:54:06 volumio ntpd[1757]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 05 17:54:06 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:06 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:54:08 volumio volumio[1006]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=9b1c01c63a2ba398d9478def725df251" http://updates.volumio.org/downloader-v1/track-device Oct 05 17:54:08 volumio volumio[1006]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 05 17:54:08 volumio volumio[1006]: Dload Upload Total Spent Left Speed Oct 05 17:54:08 volumio volumio[1006]: [132B blob data] Oct 05 17:54:08 volumio volumio[1006]: retrying in 5 seconds, trial 0 Oct 05 17:54:08 volumio volumio[1006]: info: Volumio Calling Home Oct 05 17:54:08 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:54:08 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:54:08 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:54:08 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:54:08 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:54:09 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:09 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:54:09 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:09 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:54:09 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:54:09 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:54:09 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:54:09 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:54:10 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:54:12 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:54:12 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:54:13 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Oct 05 17:54:13 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:54:13 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:54:13 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:13 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:54:13 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:54:13 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:13 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:54:13 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:14 volumio dhcpcd[835]: eth0: carrier lost Oct 05 17:54:14 volumio dhcpcd[937]: eth0: carrier lost Oct 05 17:54:14 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 05 17:54:14 volumio ifplugd(eth0)[692]: Link beat lost. Oct 05 17:54:15 volumio dhcpcd[835]: eth0: carrier acquired Oct 05 17:54:15 volumio dhcpcd[937]: eth0: carrier acquired Oct 05 17:54:15 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Oct 05 17:54:15 volumio dhcpcd[835]: eth0: IAID 32:91:ab:03 Oct 05 17:54:15 volumio dhcpcd[937]: eth0: IAID 32:91:ab:03 Oct 05 17:54:15 volumio dhcpcd[835]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:15 volumio dhcpcd[937]: eth0: rebinding lease of 192.168.1.50 Oct 05 17:54:15 volumio dhcpcd[835]: eth0: soliciting an IPv6 router Oct 05 17:54:15 volumio ifplugd(eth0)[692]: Link beat detected. Oct 05 17:54:16 volumio dhcpcd[937]: eth0: soliciting an IPv6 router Oct 05 17:54:18 volumio volumio[1006]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=9b1c01c63a2ba398d9478def725df251" http://updates.volumio.org/downloader-v1/track-device Oct 05 17:54:18 volumio volumio[1006]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 05 17:54:18 volumio volumio[1006]: Dload Upload Total Spent Left Speed Oct 05 17:54:18 volumio volumio[1006]: [132B blob data] Oct 05 17:54:18 volumio volumio[1006]: retrying in 5 seconds, trial 1 Oct 05 17:54:18 volumio volumio[1006]: info: Volumio Calling Home Oct 05 17:54:19 volumio dhcpcd[937]: eth0: probing address 192.168.1.50/24 Oct 05 17:54:20 volumio dhcpcd[835]: eth0: probing for an IPv4LL address Oct 05 17:54:20 volumio dhcpcd[835]: eth0: DHCP lease expired Oct 05 17:54:20 volumio dhcpcd[835]: eth0: soliciting a DHCP lease Oct 05 17:54:20 volumio dhcpcd[835]: eth0: offered 192.168.1.50 from 192.168.1.254 Oct 05 17:54:20 volumio dhcpcd[835]: eth0: probing address 192.168.1.50/24 Oct 05 17:54:21 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:21 volumio volumio[1006]: error: MyVolumio Plugin failed to start in a timely fashion Oct 05 17:54:21 volumio volumio[1006]: info: BOOT COMPLETED Oct 05 17:54:21 volumio volumio[1006]: [Metrics] CommandRouter: 62s 858.17ms Oct 05 17:54:21 volumio volumio[1006]: info: CoreCommandRouter::volumiosetStartupVolume Oct 05 17:54:21 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 17:54:21 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:21 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Oct 05 17:54:21 volumio volumio[1006]: info: CoreCommandRouter::Close All Modals sent Oct 05 17:54:21 volumio volumio[1006]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 05 17:54:21 volumio volumio[1006]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 05 17:54:21 volumio volumio[1006]: info: camilladsp stopping service pid 1695... Oct 05 17:54:21 volumio volumio[1006]: info: camilladsp service terminated, instance 1 Oct 05 17:54:21 volumio volumio[1006]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 05 17:54:21 volumio volumio[1006]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 05 17:54:21 volumio volumio[1006]: error: FusionDsp - ---- read samplerate skipped, rate is already updating; keeping 44100 Oct 05 17:54:21 volumio volumio[1006]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 17:54:21 volumio volumio[1006]: 2+39 records in Oct 05 17:54:21 volumio volumio[1006]: 2+39 records out Oct 05 17:54:21 volumio volumio[1006]: 220480 bytes (220 kB, 215 KiB) copied, 0.00153107 s, 144 MB/s Oct 05 17:54:22 volumio volumio[1006]: info: camilladsp spawned new process with pid 1983, instance 1, run: true Oct 05 17:54:22 volumio volumio[1006]: info: camilladsp service started and running in background, instance 1 Oct 05 17:54:22 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 05 17:54:22 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 17:54:22 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 05 17:54:24 volumio dhcpcd[937]: eth0: leased 192.168.1.50 for 86400 seconds Oct 05 17:54:24 volumio dhcpcd[937]: eth0: adding route to 192.168.1.0/24 Oct 05 17:54:24 volumio dhcpcd[937]: eth0: adding default route via 192.168.1.254 Oct 05 17:54:24 volumio avahi-daemon[562]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.50. Oct 05 17:54:24 volumio avahi-daemon[562]: New relevant interface eth0.IPv4 for mDNS. Oct 05 17:54:24 volumio avahi-daemon[562]: Registering new address record for 192.168.1.50 on eth0.IPv4. Oct 05 17:54:24 volumio ntpd[1757]: ntpd exiting on signal 15 (Terminated) Oct 05 17:54:24 volumio systemd[1]: Stopping Network Time Service... Oct 05 17:54:24 volumio systemd[1]: ntp.service: Succeeded. Oct 05 17:54:24 volumio systemd[1]: Stopped Network Time Service. Oct 05 17:54:24 volumio systemd[1]: Starting Network Time Service... Oct 05 17:54:24 volumio ntpd[2023]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 05 17:54:24 volumio ntpd[2023]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Oct 05 17:54:24 volumio systemd[1]: Started Network Time Service. Oct 05 17:54:24 volumio ntpd[2029]: proto: precision = 2.112 usec (-19) Oct 05 17:54:24 volumio ntpd[2029]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 05 17:54:24 volumio ntpd[2029]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 05 17:54:24 volumio ntpd[2029]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 100 days ago Oct 05 17:54:24 volumio ntpd[2029]: Listen and drop on 0 v6wildcard [::]:123 Oct 05 17:54:24 volumio ntpd[2029]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 05 17:54:24 volumio ntpd[2029]: Listen normally on 2 lo 127.0.0.1:123 Oct 05 17:54:24 volumio ntpd[2029]: Listen normally on 3 eth0 192.168.1.50:123 Oct 05 17:54:24 volumio ntpd[2029]: Listening on routing socket on fd #20 for interface updates Oct 05 17:54:24 volumio ntpd[2029]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:54:24 volumio ntpd[2029]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 05 17:54:24 volumio volumio[1006]: info: Discovery: adding 14615318-8f95-4a8e-b33a-9943a2fc036f Oct 05 17:54:24 volumio volumio[1006]: info: Discovery: Found device Volumio Oct 05 17:54:24 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:24 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:25 volumio dhcpcd[835]: eth0: using IPv4LL address 169.254.33.246 Oct 05 17:54:25 volumio avahi-daemon[562]: Registering new address record for 169.254.33.246 on eth0.IPv4. Oct 05 17:54:25 volumio dhcpcd[835]: eth0: adding route to 169.254.0.0/16 Oct 05 17:54:25 volumio dhcpcd[835]: eth0: adding default route Oct 05 17:54:25 volumio dhcpcd[937]: eth0: pid 835 deleted default route via 192.168.1.254 Oct 05 17:54:25 volumio ntpd[2029]: Soliciting pool server 188.213.165.209 Oct 05 17:54:25 volumio dhcpcd[835]: eth0: pid 835 deleted default route Oct 05 17:54:26 volumio dhcpcd[835]: eth0: leased 192.168.1.50 for 86400 seconds Oct 05 17:54:26 volumio dhcpcd[835]: eth0: adding route to 192.168.1.0/24 Oct 05 17:54:26 volumio dhcpcd[835]: eth0: adding default route via 192.168.1.254 Oct 05 17:54:26 volumio ntpd[2029]: Soliciting pool server 212.6.50.243 Oct 05 17:54:26 volumio dhcpcd[835]: eth0: deleting route to 169.254.0.0/16 Oct 05 17:54:26 volumio avahi-daemon[562]: Withdrawing address record for 169.254.33.246 on eth0. Oct 05 17:54:26 volumio dhcpcd[835]: eth0: pid 835 deleted default route via 192.168.1.254 Oct 05 17:54:26 volumio volumio[1338]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 05 17:54:26 volumio volumio[1006]: 0+0 records in Oct 05 17:54:26 volumio volumio[1006]: 0+0 records out Oct 05 17:54:26 volumio volumio[1006]: 0 bytes copied, 0.000272406 s, 0.0 kB/s Oct 05 17:54:26 volumio volumio[1006]: info: camilladsp spawned new process with pid 2108, instance 1, run: true Oct 05 17:54:27 volumio ntpd[2029]: Soliciting pool server 212.45.144.88 Oct 05 17:54:27 volumio ntpd[2029]: Soliciting pool server 162.159.200.123 Oct 05 17:54:27 volumio volumio[1338]: ...++++ Oct 05 17:54:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:27 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:27 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:27 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:27 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:28 volumio ntpd[2029]: Soliciting pool server 93.94.88.51 Oct 05 17:54:28 volumio ntpd[2029]: Soliciting pool server 85.199.214.99 Oct 05 17:54:28 volumio ntpd[2029]: Soliciting pool server 162.159.200.1 Oct 05 17:54:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 17:54:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:28 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 17:54:28 volumio volumio[1006]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.631&uuid=9b1c01c63a2ba398d9478def725df251" http://updates.volumio.org/downloader-v1/track-device Oct 05 17:54:28 volumio volumio[1006]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 05 17:54:28 volumio volumio[1006]: Dload Upload Total Spent Left Speed Oct 05 17:54:28 volumio volumio[1006]: [132B blob data] Oct 05 17:54:28 volumio volumio[1006]: retrying in 5 seconds, trial 2 Oct 05 17:54:28 volumio volumio[1006]: info: Volumio Calling Home Oct 05 17:54:28 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:29 volumio volumio[1006]: info: Volumio called home Oct 05 17:54:29 volumio ntpd[2029]: Soliciting pool server 93.94.88.50 Oct 05 17:54:29 volumio ntpd[2029]: Soliciting pool server 93.94.88.50 Oct 05 17:54:29 volumio ntpd[2029]: Soliciting pool server 37.247.53.178 Oct 05 17:54:30 volumio volumio[1338]: ..........++++ Oct 05 17:54:30 volumio volumio[1338]: e is 65537 (0x010001) Oct 05 17:54:30 volumio volumio[1338]: writing RSA key Oct 05 17:54:30 volumio ntpd[2029]: Soliciting pool server 89.46.74.148 Oct 05 17:54:30 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:30 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:30 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:30 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:30 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:30 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin bluetooth to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin multiroom to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin metavolumio to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin cd_controller to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 05 17:54:34 volumio volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 05 17:54:36 volumio volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 05 17:54:36 volumio volumio[1006]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 05 17:54:36 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:36 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:36 volumio volumio[1006]: info: Starting MyVolumio Remote Streaming Endpoints Oct 05 17:54:36 volumio volumio[1006]: info: MyVolumio login type: Token Oct 05 17:54:36 volumio volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 05 17:54:36 volumio volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 05 17:54:36 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:38 volumio volumio[1006]: info: Starting Streaming Service Transparent Proxy Oct 05 17:54:38 volumio volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 05 17:54:38 volumio volumio[1006]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 05 17:54:38 volumio volumio[1006]: info: Streaming services startup Oct 05 17:54:38 volumio volumio[1006]: info: Starting Streaming Daemon Oct 05 17:54:38 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 05 17:54:38 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:38 volumio volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 05 17:54:38 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:38 volumio volumio[1006]: error: Cannot start Volumio Streaming Daemon Oct 05 17:54:38 volumio volumio[1006]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 05 17:54:38 volumio volumio[1006]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 05 17:54:38 volumio volumio[1006]: error: MyVolumio Custom Token format not valid, refreshing it Oct 05 17:54:39 volumio volumio[1006]: info: MyVolumio login type: Token Oct 05 17:54:39 volumio volumio[1006]: STREAMING PROXY: Starting server on port 3245 Oct 05 17:54:39 volumio volumio[1006]: Node JS runtime: 14 Oct 05 17:54:40 volumio volumio[1006]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 05 17:54:40 volumio volumio[1006]: info: MyVolumio token set successfully Oct 05 17:54:40 volumio volumio[1006]: info: MYVOLUMIO: Adding device Oct 05 17:54:40 volumio volumio[1006]: info: MYVOLUMIO: Evaluating Server Oct 05 17:54:41 volumio volumio[1006]: info: MyVolumio status changed Oct 05 17:54:41 volumio volumio[1006]: info: Streaming services startup Oct 05 17:54:41 volumio volumio[1006]: info: Starting Streaming Daemon Oct 05 17:54:41 volumio volumio[1006]: info: Removing browser output: myVolumio user plan is not superstar Oct 05 17:54:41 volumio volumio[1006]: info: Removing audio output: Oct 05 17:54:41 volumio volumio[1006]: info: Stoppping Tunnel 1 Oct 05 17:54:41 volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 05 17:54:41 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:41 volumio sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 05 17:54:41 volumio sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:41 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:41 volumio volumio[1006]: error: Cannot start Volumio Streaming Daemon Oct 05 17:54:41 volumio volumio[1006]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 05 17:54:41 volumio volumio[1006]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 05 17:54:41 volumio sudo[2169]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:41 volumio volumio[1006]: info: Remote SSH Stopped Oct 05 17:54:41 volumio volumio[1006]: info: Setting Geolocation for MyVolumio to eu6 Oct 05 17:54:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:41 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:42 volumio volumio[1006]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 05 17:54:42 volumio volumio[1006]: info: Updating MyVolumio device info Oct 05 17:54:42 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:43 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:43 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:43 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:43 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:43 volumio volumio[1006]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.58 UA: Dart/3.4 (dart:io) Total Clients: 4 Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:43 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 05 17:54:43 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 05 17:54:44 volumio volumio[1006]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 05 17:54:44 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:46 volumio volumio[1006]: info: MYVOLUMIO: Adding device Oct 05 17:54:46 volumio volumio[1006]: info: MYVOLUMIO: Evaluating Server Oct 05 17:54:47 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 17:54:47 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:47 volumio volumio[1006]: info: Setting Geolocation for MyVolumio to eu6 Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:47 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:47 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 17:54:47 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:47 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:47 volumio volumio[1006]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.58 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 5 Oct 05 17:54:47 volumio sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 05 17:54:47 volumio sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:47 volumio volumio[1006]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 05 17:54:47 volumio sudo[2232]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:47 volumio sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 05 17:54:47 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 17:54:47 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Oct 05 17:54:47 volumio volumio[1006]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.58 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Total Clients: 6 Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 17:54:47 volumio volumio[1006]: info: Received Get System Info Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:47 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:47 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:47 volumio volumio[1006]: info: Listing playlists Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 17:54:47 volumio volumio[1006]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 17:54:48 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 17:54:48 volumio volumio[1006]: info: Updating MyVolumio device info Oct 05 17:54:48 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:48 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:48 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 17:54:48 volumio volumio[1006]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 05 17:54:48 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:48 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 17:54:49 volumio volumio[1006]: info: Received Get System Info Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 17:54:49 volumio volumio[1006]: info: Discovery: Getting this device information Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:49 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 17:54:49 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 17:54:51 volumio volumio[1006]: info: CoreCommandRouter::volumioGetQueue Oct 05 17:54:51 volumio volumio[1006]: info: CoreStateMachine::getQueue Oct 05 17:54:51 volumio volumio[1006]: info: CorePlayQueue::getQueue Oct 05 17:54:51 volumio volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 05 17:54:51 volumio volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 05 17:54:51 volumio volumio[1006]: info: CoreCommandRouter::volumioGetState Oct 05 17:54:51 volumio volumio[1006]: info: CorePlayQueue::getTrack 0 Oct 05 17:54:51 volumio wpa_supplicant[822]: wlan0: Failed to initiate sched scan Oct 05 17:54:54 volumio volumio[1006]: info: Preload queue cleared Oct 05 17:54:54 volumio volumio[1006]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 05 17:54:54 volumio volumio[1006]: info: CoreStateMachine::ClearQueue Oct 05 17:54:54 volumio volumio[1006]: info: CoreStateMachine::stop Oct 05 17:54:54 volumio volumio[1006]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 17:54:54 volumio volumio[1006]: info: CorePlayQueue::clearPlayQueue Oct 05 17:54:54 volumio volumio[1006]: info: CorePlayQueue::saveQueue Oct 05 17:54:54 volumio volumio[1006]: info: CoreCommandRouter::volumioPushQueue Oct 05 17:54:54 volumio volumio[1006]: info: CoreStateMachine::addQueueItems Oct 05 17:54:54 volumio volumio[1006]: info: CorePlayQueue::addQueueItems Oct 05 17:54:54 volumio volumio[1006]: info: Preload queue cleared Oct 05 17:54:54 volumio volumio[1006]: info: Adding Item to queue: music-library/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan) Oct 05 17:54:54 volumio volumio[1006]: info: Exploding uri music-library/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan) in service mpd Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F01%20-%20The%20Post%20War%20Dream.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/01 - The Post War Dream.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F02%20-%20Your%20Possible%20Pasts.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/02 - Your Possible Pasts.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F03%20-%20One%20of%20the%20Few.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/03 - One of the Few.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F04%20-%20When%20the%20Tigers%20Broke%20Free.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/04 - When the Tigers Broke Free.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F05%20-%20The%20Hero's%20Return.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/05 - The Hero's Return.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F06%20-%20The%20Gunner's%20Dream.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/06 - The Gunner's Dream.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F07%20-%20Paranoid%20Eyes.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/07 - Paranoid Eyes.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F08%20-%20Get%20Your%20Filthy%20Hands%20Off%20My%20Desert.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/08 - Get Your Filthy Hands Off My Desert.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F09%20-%20The%20Fletcher%20Memorial%20Home.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/09 - The Fletcher Memorial Home.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F10%20-%20Southampton%20Dock.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/10 - Southampton Dock.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F11%20-%20The%20Final%20Cut.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/11 - The Final Cut.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F12%20-%20Not%20Now%20John.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/12 - Not Now John.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2F13%20-%20Two%20Suns%20In%20The%20Sunset.flac&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/13 - Two Suns In The Sunset.flac Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2FThe%20Final%20Cut%20-%20flac.cue&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/The Final Cut - flac.cue Oct 05 17:54:54 volumio volumio[1006]: info: ALBUMART /albumart?cacheid=904&web=Pink%20Floyd/The%20Final%20Cut/extralarge&path=%2Fmnt%2FUSB%2F889D-D223%2FPink%20Floyd%20-%20The%20Final%20Cut%201983%20%5BEAC%20-%20FLAC%5D%20(oan)%2FThe%20Final%20Cut%20-%20flac.cue&metadata=false Oct 05 17:54:54 volumio volumio[1006]: info: URI /mnt/USB/889D-D223/Pink Floyd - The Final Cut 1983 [EAC - FLAC] (oan)/The Final Cut - flac.cue Oct 05 17:54:54 volumio volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 17:54:54 volumio volumio[1006]: Error: Unable to resolve or reject the same promise twice Oct 05 17:54:54 volumio volumio[1006]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 05 17:54:54 volumio volumio[1006]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Oct 05 17:54:54 volumio volumio[1006]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 05 17:54:54 volumio volumio[1006]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 05 17:54:54 volumio volumio[1006]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 05 17:54:54 volumio volumio[1006]: at Socket.emit (events.js:315:20) Oct 05 17:54:54 volumio volumio[1006]: at addChunk (internal/streams/readable.js:309:12) Oct 05 17:54:54 volumio volumio[1006]: at readableAddChunk (internal/streams/readable.js:280:11) Oct 05 17:54:54 volumio volumio[1006]: at Socket.Readable.push (internal/streams/readable.js:223:10) Oct 05 17:54:54 volumio volumio[1006]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Oct 05 17:54:54 volumio volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 17:54:55 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 17:53 Oct 05 17:54:55 volumio sudo[2246]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"