-- Logs begin at Mon 2024-09-09 09:24:38 +03, end at Mon 2024-09-09 09:26:59 +03. -- Sep 09 09:25:00 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 09:25:00 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:25:00 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Sep 09 09:25:00 volumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 09:25:00 volumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:25:00 volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Sep 09 09:25:00 volumio sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 09 09:25:00 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 09 09:25:00 volumio volumio[855]: info: Received Get System Info Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:25:00 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:00 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:25:00 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Sep 09 09:25:00 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 09 09:25:00 volumio volumio[855]: info: Upmpdcli Daemon Started Sep 09 09:25:00 volumio volumio[855]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 09 09:25:00 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 09 09:25:03 volumio volumio[855]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=92c7b529f44ddfa12b96a250a9bfc1fb" http://updates.volumio.org/downloader-v1/track-device Sep 09 09:25:03 volumio volumio[855]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 09 09:25:03 volumio volumio[855]: Dload Upload Total Spent Left Speed Sep 09 09:25:03 volumio volumio[855]: [132B blob data] Sep 09 09:25:03 volumio volumio[855]: retrying in 5 seconds, trial 0 Sep 09 09:25:03 volumio volumio[855]: info: Volumio Calling Home Sep 09 09:25:06 volumio kernel: usb 1-6: USB disconnect, device number 2 Sep 09 09:25:06 volumio thd[638]: Error reading device '/dev/input/event2' Sep 09 09:25:06 volumio thd[638]: Error reading device '/dev/input/event3' Sep 09 09:25:07 volumio thd[638]: Error reading device '/dev/input/event4' Sep 09 09:25:08 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 09:25:10 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 09 09:25:11 volumio kernel: usb 1-6: new low-speed USB device number 3 using ohci-pci Sep 09 09:25:11 volumio kernel: usb 1-6: New USB device found, idVendor=192f, idProduct=0916, bcdDevice= 2.00 Sep 09 09:25:11 volumio kernel: usb 1-6: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Sep 09 09:25:11 volumio kernel: usb 1-6: Product: USB Optical Mouse Sep 09 09:25:11 volumio kernel: input: USB Optical Mouse as /devices/pci0000:00/0000:00:02.0/usb1/1-6/1-6:1.0/0003:192F:0916.0003/input/input15 Sep 09 09:25:11 volumio kernel: hid-generic 0003:192F:0916.0003: input,hidraw0: USB HID v1.11 Mouse [USB Optical Mouse] on usb-0000:00:02.0-6/input0 Sep 09 09:25:11 volumio kernel: usbcore: registered new interface driver usbmouse Sep 09 09:25:11 volumio systemd-udevd[1557]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 09 09:25:11 volumio systemd-udevd[1559]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 09 09:25:13 volumio volumio[855]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=92c7b529f44ddfa12b96a250a9bfc1fb" http://updates.volumio.org/downloader-v1/track-device Sep 09 09:25:13 volumio volumio[855]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 09 09:25:13 volumio volumio[855]: Dload Upload Total Spent Left Speed Sep 09 09:25:13 volumio volumio[855]: [132B blob data] Sep 09 09:25:13 volumio volumio[855]: retrying in 5 seconds, trial 1 Sep 09 09:25:13 volumio volumio[855]: info: Volumio Calling Home Sep 09 09:25:15 volumio volumio[855]: error: MyVolumio Plugin failed to start in a timely fashion Sep 09 09:25:15 volumio volumio[855]: info: BOOT COMPLETED Sep 09 09:25:15 volumio volumio[855]: [Metrics] CommandRouter: 29s 550.03ms Sep 09 09:25:15 volumio volumio[855]: info: CoreCommandRouter::volumiosetStartupVolume Sep 09 09:25:15 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 09:25:15 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 09:25:15 volumio volumio[855]: info: CoreCommandRouter::Close All Modals sent Sep 09 09:25:15 volumio volumio[855]: info: CoreCommandRouter::Close All Modals sent Sep 09 09:25:15 volumio volumio[855]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Sep 09 09:25:15 volumio volumio[855]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Sep 09 09:25:15 volumio volumio[855]: aplay: main:828: audio open error: No such device Sep 09 09:25:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 09 09:25:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 09 09:25:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Sep 09 09:25:16 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 09 09:25:23 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 09:25:23 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 09:25:23 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 09:25:23 volumio volumio[855]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.742&uuid=92c7b529f44ddfa12b96a250a9bfc1fb" http://updates.volumio.org/downloader-v1/track-device Sep 09 09:25:23 volumio volumio[855]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 09 09:25:23 volumio volumio[855]: Dload Upload Total Spent Left Speed Sep 09 09:25:23 volumio volumio[855]: [132B blob data] Sep 09 09:25:23 volumio volumio[855]: retrying in 5 seconds, trial 2 Sep 09 09:25:23 volumio volumio[855]: info: Volumio Calling Home Sep 09 09:25:25 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 09 09:25:32 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 09:25:32 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 09 09:25:32 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 09 09:25:32 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Sep 09 09:25:33 volumio volumio[855]: info: Received Get System Version Sep 09 09:25:33 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 09:25:45 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:45 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:47 volumio volumio[855]: info: CoreCommandRouter::volumioGetQueue Sep 09 09:25:47 volumio volumio[855]: info: CoreStateMachine::getQueue Sep 09 09:25:47 volumio volumio[855]: info: CorePlayQueue::getQueue Sep 09 09:25:50 volumio ntpd[827]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 09 09:25:50 volumio ntpd[827]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 09 09:25:51 volumio ntpd[827]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 09 09:25:52 volumio ntpd[827]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 09 09:25:53 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:53 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: Received Get System Info Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:25:59 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 09:25:59 volumio volumio[855]: info: Received Get System Info Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:25:59 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: Listing playlists Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetQueue Sep 09 09:25:59 volumio volumio[855]: info: CoreStateMachine::getQueue Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getQueue Sep 09 09:25:59 volumio volumio[855]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: Received Get System Info Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:25:59 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 09:25:59 volumio volumio[855]: info: Received Get System Info Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:25:59 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:25:59 volumio volumio[855]: info: Listing playlists Sep 09 09:25:59 volumio volumio[855]: info: CoreCommandRouter::volumioGetQueue Sep 09 09:25:59 volumio volumio[855]: info: CoreStateMachine::getQueue Sep 09 09:25:59 volumio volumio[855]: info: CorePlayQueue::getQueue Sep 09 09:26:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 09 09:26:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 09 09:26:09 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 09:26:09 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 09:26:11 volumio systemd[1]: nmbd.service: Start operation timed out. Terminating. Sep 09 09:26:11 volumio systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Sep 09 09:26:11 volumio systemd[1]: nmbd.service: Failed with result 'timeout'. Sep 09 09:26:11 volumio systemd[1]: Failed to start Samba NMB Daemon. Sep 09 09:26:11 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 09 09:26:11 volumio winbindd[1615]: [2024/09/09 09:26:11.911137, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 09 09:26:11 volumio winbindd[1615]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 09 09:26:12 volumio winbindd[1615]: [2024/09/09 09:26:12.034040, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 09 09:26:12 volumio systemd[1]: Started Samba Winbind Daemon. Sep 09 09:26:12 volumio winbindd[1615]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 09 09:26:12 volumio systemd[1]: Starting Samba SMB Daemon... Sep 09 09:26:12 volumio smbd[1619]: [2024/09/09 09:26:12.343737, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 09 09:26:12 volumio systemd[1]: Started Samba SMB Daemon. Sep 09 09:26:12 volumio smbd[1619]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 09 09:26:12 volumio systemd[1]: Reached target Multi-User System. Sep 09 09:26:12 volumio systemd[1]: Reached target Graphical Interface. Sep 09 09:26:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 09 09:26:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 09 09:26:12 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 09 09:26:12 volumio systemd[1]: Startup finished in 14.439s (kernel) + 1min 34.903s (userspace) = 1min 49.343s. Sep 09 09:26:12 volumio volumio[855]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: Received Get System Info Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:26:13 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 09:26:13 volumio volumio[855]: info: Received Get System Info Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:26:13 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: Listing playlists Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetQueue Sep 09 09:26:13 volumio volumio[855]: info: CoreStateMachine::getQueue Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getQueue Sep 09 09:26:13 volumio volumio[855]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: Received Get System Info Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:26:13 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 09:26:13 volumio volumio[855]: info: Received Get System Info Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 09:26:13 volumio volumio[855]: info: Discovery: Getting this device information Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:13 volumio volumio[855]: info: Listing playlists Sep 09 09:26:13 volumio volumio[855]: info: CoreCommandRouter::volumioGetQueue Sep 09 09:26:13 volumio volumio[855]: info: CoreStateMachine::getQueue Sep 09 09:26:13 volumio volumio[855]: info: CorePlayQueue::getQueue Sep 09 09:26:19 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 09:26:19 volumio volumio[855]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Sep 09 09:26:19 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 09:26:19 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 09 09:26:19 volumio sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 09 09:26:19 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:19 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:19 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:19 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 09:26:19 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 09 09:26:19 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 09 09:26:19 volumio sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 09:26:19 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 09 09:26:19 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio volumio[855]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 09 09:26:19 volumio volumio[855]: wlan0 Interface doesn't support scanning. Sep 09 09:26:19 volumio volumio[855]: info: Cannot use regular scanning, forcing with ap-force Sep 09 09:26:19 volumio sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 09 09:26:19 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:19 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:19 volumio volumio[855]: command failed: No such device (-19) Sep 09 09:26:19 volumio volumio[855]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 09 09:26:19 volumio volumio[855]: command failed: No such device (-19) Sep 09 09:26:22 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 09:26:23 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 09:26:25 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:25 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:42 volumio kernel: forcedeth 0000:00:07.0 eth0: link up Sep 09 09:26:42 volumio dhcpcd[763]: eth0: carrier acquired Sep 09 09:26:42 volumio dhcpcd[763]: DUID 00:04:00:02:00:03:00:04:00:05:00:06:00:07:00:08:00:09 Sep 09 09:26:42 volumio dhcpcd[763]: eth0: IAID 22:ee:df:69 Sep 09 09:26:42 volumio dhcpcd[763]: eth0: adding address fe80::dc4:d60a:7829:8699 Sep 09 09:26:42 volumio dhcpcd[763]: ipv6_addaddr1: Permission denied Sep 09 09:26:43 volumio ifplugd(eth0)[797]: Link beat detected. Sep 09 09:26:43 volumio ifplugd(eth0)[797]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 09 09:26:43 volumio ifplugd(eth0)[797]: client: ifup: interface eth0 already configured Sep 09 09:26:43 volumio ifplugd(eth0)[797]: Program executed successfully. Sep 09 09:26:43 volumio dhcpcd[763]: eth0: rebinding lease of 192.168.100.2 Sep 09 09:26:43 volumio dhcpcd[763]: eth0: NAK: from 192.168.100.1 Sep 09 09:26:43 volumio dhcpcd[763]: eth0: soliciting a DHCP lease Sep 09 09:26:43 volumio dhcpcd[763]: eth0: soliciting an IPv6 router Sep 09 09:26:45 volumio dhcpcd[763]: eth0: offered 192.168.100.5 from 192.168.100.1 Sep 09 09:26:45 volumio dhcpcd[763]: eth0: probing address 192.168.100.5/24 Sep 09 09:26:50 volumio dhcpcd[763]: eth0: leased 192.168.100.5 for 86400 seconds Sep 09 09:26:50 volumio dhcpcd[763]: eth0: adding route to 192.168.100.0/24 Sep 09 09:26:50 volumio dhcpcd[763]: eth0: adding default route via 192.168.100.1 Sep 09 09:26:50 volumio avahi-daemon[643]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.100.5. Sep 09 09:26:50 volumio avahi-daemon[643]: New relevant interface eth0.IPv4 for mDNS. Sep 09 09:26:50 volumio avahi-daemon[643]: Registering new address record for 192.168.100.5 on eth0.IPv4. Sep 09 09:26:51 volumio ntpd[827]: ntpd exiting on signal 15 (Terminated) Sep 09 09:26:51 volumio systemd[1]: Stopping Network Time Service... Sep 09 09:26:51 volumio systemd[1]: ntp.service: Succeeded. Sep 09 09:26:51 volumio systemd[1]: Stopped Network Time Service. Sep 09 09:26:51 volumio systemd[1]: Starting Network Time Service... Sep 09 09:26:51 volumio ntpd[1776]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 09 09:26:51 volumio ntpd[1776]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 09 09:26:51 volumio systemd[1]: Started Network Time Service. Sep 09 09:26:51 volumio ntpd[1782]: proto: precision = 0.099 usec (-23) Sep 09 09:26:51 volumio ntpd[1782]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 09 09:26:51 volumio ntpd[1782]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 09 09:26:51 volumio ntpd[1782]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 622 days ago Sep 09 09:26:51 volumio ntpd[1782]: Listen and drop on 0 v6wildcard [::]:123 Sep 09 09:26:51 volumio ntpd[1782]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 09 09:26:51 volumio ntpd[1782]: Listen normally on 2 lo 127.0.0.1:123 Sep 09 09:26:51 volumio ntpd[1782]: Listen normally on 3 eth0 192.168.100.5:123 Sep 09 09:26:51 volumio ntpd[1782]: Listening on routing socket on fd #20 for interface updates Sep 09 09:26:51 volumio ntpd[1782]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 09:26:51 volumio ntpd[1782]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 09 09:26:52 volumio volumio[855]: info: Discovery: adding bf72cd69-5b95-42ad-877e-2f129baf8c58 Sep 09 09:26:52 volumio volumio[855]: info: Discovery: Found device Volumio Sep 09 09:26:52 volumio volumio[855]: info: CoreCommandRouter::volumioGetState Sep 09 09:26:52 volumio ntpd[1782]: Soliciting pool server 178.124.164.105 Sep 09 09:26:52 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Sep 09 09:26:53 volumio ntpd[1782]: Soliciting pool server 31.130.200.50 Sep 09 09:26:53 volumio ntpd[1782]: Soliciting pool server 194.158.196.171 Sep 09 09:26:54 volumio ntpd[1782]: Soliciting pool server 178.124.134.106 Sep 09 09:26:54 volumio ntpd[1782]: Soliciting pool server 134.17.27.157 Sep 09 09:26:55 volumio ntpd[1782]: Soliciting pool server 178.159.240.10 Sep 09 09:26:55 volumio volumio[855]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 09:26:55 volumio volumio[855]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Sep 09 09:26:55 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin bluetooth to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin multiroom to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin metavolumio to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin cd_controller to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 09 09:26:55 volumio volumio[855]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 09 09:26:55 volumio ntpd[1782]: Soliciting pool server 82.209.210.87 Sep 09 09:26:56 volumio ntpd[1782]: Soliciting pool server 194.158.196.172 Sep 09 09:26:56 volumio ntpd[1782]: Soliciting pool server 2a0c:b1c2::c Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 09 09:26:56 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 09:26:56 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 09:26:56 volumio volumio[855]: info: Starting MyVolumio Remote Streaming Endpoints Sep 09 09:26:56 volumio volumio[855]: info: MyVolumio login type: Token Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 09 09:26:56 volumio volumio[855]: info: Starting Streaming Service Transparent Proxy Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 09 09:26:56 volumio volumio[855]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 09 09:26:56 volumio volumio[855]: info: Streaming services startup Sep 09 09:26:56 volumio volumio[855]: info: Starting Streaming Daemon Sep 09 09:26:56 volumio sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 09:26:56 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio volumio[855]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 09 09:26:57 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 09 09:26:57 volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 09 09:26:57 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:57 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:57 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 09 09:26:57 volumio sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 09:26:57 volumio sudo[1836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1831]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 09 09:26:57 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 09 09:26:57 volumio sudo[1836]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 09:26:57 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio volumio[855]: error: Cannot start Volumio Streaming Daemon Sep 09 09:26:57 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio volumio[855]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 09:26:57 volumio volumio[855]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 09:26:57 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 09 09:26:57 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio volumio[855]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 09 09:26:57 volumio volumio[855]: wlan0 Interface doesn't support scanning. Sep 09 09:26:57 volumio volumio[855]: info: Cannot use regular scanning, forcing with ap-force Sep 09 09:26:57 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 09 09:26:57 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:57 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:57 volumio volumio[855]: command failed: No such device (-19) Sep 09 09:26:57 volumio volumio[855]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 09 09:26:57 volumio volumio[855]: command failed: No such device (-19) Sep 09 09:26:57 volumio volumio[855]: STREAMING PROXY: Starting server on port 3245 Sep 09 09:26:57 volumio volumio[855]: Node JS runtime: 14 Sep 09 09:26:57 volumio volumio[855]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 09 09:26:58 volumio volumio[855]: info: MyVolumio token set successfully Sep 09 09:26:58 volumio volumio[855]: info: MYVOLUMIO: Adding device Sep 09 09:26:58 volumio volumio[855]: info: MYVOLUMIO: Evaluating Server Sep 09 09:26:58 volumio volumio[855]: info: MyVolumio status changed Sep 09 09:26:58 volumio volumio[855]: info: Streaming services startup Sep 09 09:26:58 volumio volumio[855]: info: Starting Streaming Daemon Sep 09 09:26:58 volumio volumio[855]: info: Removing browser output: myVolumio user plan is not superstar Sep 09 09:26:58 volumio volumio[855]: info: Removing audio output: Sep 09 09:26:59 volumio volumio[855]: info: Stoppping Tunnel 1 Sep 09 09:26:59 volumio sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 09:26:59 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:59 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:59 volumio volumio[855]: error: Cannot start Volumio Streaming Daemon Sep 09 09:26:59 volumio volumio[855]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 09:26:59 volumio volumio[855]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 09:26:59 volumio sudo[1879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 09 09:26:59 volumio sudo[1879]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 09 09:26:59 volumio sudo[1879]: pam_unix(sudo:session): session closed for user root Sep 09 09:26:59 volumio volumio[855]: info: Remote SSH Stopped Sep 09 09:26:59 volumio ntpd[1782]: receive: Unexpected origin timestamp 0xea8913b4.09aa9afb does not match aorg 0000000000.00000000 from server@134.17.27.157 xmt 0xea8913b3.7ac7a983 Sep 09 09:26:59 volumio ntpd[1782]: receive: Unexpected origin timestamp 0xea8913b4.09b02441 does not match aorg 0000000000.00000000 from server@178.124.164.105 xmt 0xea8913b3.7acabae1 Sep 09 09:26:59 volumio volumio[855]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Sep 09 09:26:59 volumio volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 09:26:59 volumio volumio[855]: Error: Unable to resolve or reject the same promise twice Sep 09 09:26:59 volumio volumio[855]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 09 09:26:59 volumio volumio[855]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Sep 09 09:26:59 volumio volumio[855]: at Socket.emit (events.js:412:35) Sep 09 09:26:59 volumio volumio[855]: at endReadableNT (internal/streams/readable.js:1333:12) Sep 09 09:26:59 volumio volumio[855]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Sep 09 09:26:59 volumio volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 09:26:59 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-09 09:25 Sep 09 09:26:59 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"