-- Logs begin at Mon 2024-07-22 17:22:29 UTC, end at Tue 2024-09-03 18:07:55 UTC. -- Sep 03 18:06:13 volumio ntpd[538]: receive: Unexpected origin timestamp 0xea491442.dc8d6249 does not match aorg 0000000000.00000000 from server@80.50.102.114 xmt 0xea81ce95.3462eeb8 Sep 03 18:06:13 volumio systemd[1]: Starting Daily apt download activities... Sep 03 18:06:13 volumio nmbd[534]: [2024/09/03 18:06:13.653019, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 03 18:06:13 volumio nmbd[534]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.39 for name WORKGROUP<1d>. Sep 03 18:06:13 volumio nmbd[534]: This response was from IP 192.168.100.35, reporting an IP address of 192.168.100.35. Sep 03 18:06:17 volumio systemd[1]: Reloading. Sep 03 18:06:18 volumio volumio[830]: info: Loading plugin "upnp_browser"... Sep 03 18:06:20 volumio volumio-remote-updater[467]: [2024-09-03 18:06:20] [connect] Successful connection Sep 03 18:06:22 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 03 18:06:24 volumio volumio[830]: info: Loading plugin "alarm-clock"... Sep 03 18:06:24 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 03 18:06:25 volumio systemd[1]: apt-daily.service: Succeeded. Sep 03 18:06:25 volumio systemd[1]: Started Daily apt download activities. Sep 03 18:06:25 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 03 18:06:26 volumio volumio[830]: info: Loading plugin "airplay_emulation"... Sep 03 18:06:26 volumio firststart.sh[832]: rescue-ssh.target is a disabled or a static unit, not starting it. Sep 03 18:06:26 volumio volumio[830]: info: Starting Shairport Sync Sep 03 18:06:26 volumio volumio[830]: info: Loading plugin "last_100"... Sep 03 18:06:27 volumio volumio[830]: info: Loading plugin "webradio"... Sep 03 18:06:28 volumio volumio[830]: info: Loading plugin "i2s_dacs"... Sep 03 18:06:28 volumio volumio[830]: info: I2S DAC not set, start Auto-detection Sep 03 18:06:28 volumio volumio[830]: info: Loading plugin "volumiodiscovery"... Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 18:06:29 volumio node[830]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 18:06:29 volumio node[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** For more information see Sep 03 18:06:29 volumio node[830]: *** WARNING *** For more information see Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 18:06:29 volumio volumio[830]: *** WARNING *** For more information see Sep 03 18:06:29 volumio node[830]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 18:06:29 volumio node[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 18:06:29 volumio node[830]: *** WARNING *** For more information see Sep 03 18:06:29 volumio volumio[830]: info: Discovery: Started advertising with name: Volumio Sep 03 18:06:29 volumio firststart.sh[832]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Sep 03 18:06:29 volumio firststart.sh[832]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Sep 03 18:06:29 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 18:06:29 volumio volumio[830]: info: Loading plugin "outputs"... Sep 03 18:06:29 volumio volumio[830]: info: Loading plugin "albumart"... Sep 03 18:06:30 volumio volumio[830]: info: Plugin example_plugin is not enabled Sep 03 18:06:30 volumio systemd[1]: Reloading. Sep 03 18:06:30 volumio volumio[830]: info: Loading plugin "inputs"... Sep 03 18:06:30 volumio volumio[830]: info: Loading plugin "updater_comm"... Sep 03 18:06:35 volumio volumio-remote-updater[467]: [2024-09-03 18:06:35] [connect] Successful connection Sep 03 18:06:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 03 18:06:35 volumio volumio[830]: info: Plugin mpdemulation is not enabled Sep 03 18:06:35 volumio volumio[830]: info: Loading plugin "rest_api"... Sep 03 18:06:36 volumio volumio[830]: info: Loading plugin "websocket"... Sep 03 18:06:36 volumio volumio[830]: info: Starting Socket.io Server version 2.3.0 Sep 03 18:06:36 volumio volumio[830]: info: Loading i18n strings for locale en Sep 03 18:06:36 volumio volumio[830]: Updating browse sources language Sep 03 18:06:36 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 18:06:38 volumio volumio[830]: Forking 1 albumart workers Sep 03 18:06:38 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 03 18:06:38 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 03 18:06:39 volumio firststart.sh[832]: Enabling SSH for first boot Sep 03 18:06:40 volumio systemd[1]: Starting OpenBSD Secure Shell server... Sep 03 18:06:40 volumio sshd[1258]: Server listening on 0.0.0.0 port 22. Sep 03 18:06:40 volumio sshd[1258]: Server listening on :: port 22. Sep 03 18:06:40 volumio systemd[1]: Started OpenBSD Secure Shell server. Sep 03 18:06:40 volumio firststart.sh[832]: Disabling firststart service Sep 03 18:06:40 volumio firststart.sh[832]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Sep 03 18:06:41 volumio systemd[1]: Reloading. Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::initPlayerControls Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 18:06:42 volumio volumio[830]: Express server listening on port 3000 Sep 03 18:06:42 volumio volumio[830]: [Metrics] WebUI: 62s 416.41ms Sep 03 18:06:43 volumio volumio[830]: info: CoreStateMachine::resetVolumioState Sep 03 18:06:43 volumio volumio[830]: info: CoreStateMachine::getcurrentVolume Sep 03 18:06:43 volumio volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 18:06:43 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:06:43 volumio volumio[830]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 03 18:06:43 volumio volumio[830]: info: CoreStateMachine::getcurrentVolume Sep 03 18:06:43 volumio volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 18:06:44 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:06:44 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:44 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 03 18:06:45 volumio sudo[1281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:06:45 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:45 volumio volumio[830]: info: Cannot read play queue from file Sep 03 18:06:45 volumio sudo[1281]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:45 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:47 volumio firststart.sh[832]: Finalizing Sep 03 18:06:48 volumio systemd[1]: firststart.service: Succeeded. Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:06:49 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:06:49 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:06:49 volumio volumio[830]: info: VolumeController:: Volume=86 Mute =false Sep 03 18:06:49 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:06:49 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:06:49 volumio volumio[830]: info: CoreStateMachine::updateTrackBlock Sep 03 18:06:49 volumio volumio[830]: info: CorePlayQueue::getTrackBlock Sep 03 18:06:49 volumio volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 18:06:50 volumio volumio[830]: info: VolumeController:: Volume=86 Mute =false Sep 03 18:06:50 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:06:50 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:50 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:06:50 volumio volumio[830]: info: CoreStateMachine::updateTrackBlock Sep 03 18:06:50 volumio volumio-remote-updater[467]: [2024-09-03 18:06:50] [connect] Successful connection Sep 03 18:06:50 volumio volumio[830]: info: CorePlayQueue::getTrackBlock Sep 03 18:06:50 volumio volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 18:06:50 volumio volumio[830]: info: Setting Device type: Raspberry PI Sep 03 18:06:50 volumio volumio-remote-updater[467]: [2024-09-03 18:06:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725386810 101 Sep 03 18:06:50 volumio volumio[830]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 03 18:06:51 volumio volumio[830]: info: Discovery: adding 15a95d90-5442-4066-abce-8eeddd8e7491 Sep 03 18:06:51 volumio volumio[830]: info: Discovery: Found device Volumio Sep 03 18:06:51 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:06:51 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:51 volumio volumio[830]: info: VolumeController:: Volume=86 Mute =false Sep 03 18:06:51 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:06:51 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:51 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:06:51 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 18:06:51 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 03 18:06:51 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:51 volumio volumio[830]: info: Discovery: this is already registered, 15a95d90-5442-4066-abce-8eeddd8e7491 Sep 03 18:06:51 volumio volumio[830]: info: Discovery: Found device Volumio Sep 03 18:06:51 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:06:51 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:51 volumio volumio[830]: info: VolumeController:: Volume=86 Mute =false Sep 03 18:06:51 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:06:51 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:51 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:06:52 volumio volumio[830]: info: Upmpdcli Daemon Started Sep 03 18:06:52 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:06:52 volumio volumio[830]: Starting albumart workers Sep 03 18:06:52 volumio volumio[830]: info: Completed loading Core Plugins Sep 03 18:06:52 volumio volumio[830]: info: Preparing to generate the ALSA configuration file Sep 03 18:06:52 volumio volumio[830]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 03 18:06:53 volumio volumio[830]: info: Asound.conf file written Sep 03 18:06:53 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 03 18:06:53 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:53 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:54 volumio volumio[830]: No state is present for card b1 Sep 03 18:06:54 volumio volumio[830]: /usr/share/alsa/init/bcm2835:14: control element not found Sep 03 18:06:54 volumio volumio[830]: No state is present for card b1 Sep 03 18:06:54 volumio volumio[830]: No state is present for card Headphones Sep 03 18:06:54 volumio volumio[830]: /usr/share/alsa/init/bcm2835:20: control element not found Sep 03 18:06:54 volumio volumio[830]: No state is present for card Headphones Sep 03 18:06:54 volumio volumio[830]: info: Output device has changed, restarting MPD Sep 03 18:06:54 volumio volumio[830]: info: Output device has changed, restarting Shairport Sync Sep 03 18:06:54 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:54 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:06:54 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 18:06:54 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:54 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:55 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 18:06:55 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:55 volumio systemd[1]: Listening on mpd.socket. Sep 03 18:06:55 volumio systemd[1]: Starting Music Player Daemon... Sep 03 18:06:55 volumio volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 03 18:06:55 volumio volumio[830]: info: ___________ START PLUGINS ___________ Sep 03 18:06:55 volumio volumio[830]: info: Setting Hotspot Unique name on first start: Volumio-EAD45 Sep 03 18:06:55 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Sep 03 18:06:55 volumio volumio[830]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Sep 03 18:06:55 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Sep 03 18:06:55 volumio volumio[830]: info: CoreCommandRouter::Reload Ui Sep 03 18:06:55 volumio volumio[830]: info: ControllerMpd::onStart: Initializing MPD Sep 03 18:06:55 volumio volumio[830]: info: Creating MPD Configuration file Sep 03 18:06:56 volumio sudo[1318]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 18:06:56 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:56 volumio sudo[1318]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 03 18:06:56 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:56 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 18:06:56 volumio volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 18:06:56 volumio volumio[830]: info: [1725386816379] CoreMusicLibrary::Adding element Media Servers Sep 03 18:06:56 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 18:06:56 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 18:06:56 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:56 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:57 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 18:06:57 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 03 18:06:57 volumio systemd[1]: mpd.service: Succeeded. Sep 03 18:06:57 volumio systemd[1]: Stopped Music Player Daemon. Sep 03 18:06:57 volumio systemd[1]: Starting Music Player Daemon... Sep 03 18:06:58 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:58 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:06:58 volumio sudo[1352]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 18:06:58 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:06:59 volumio sudo[1352]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 03 18:06:59 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root Sep 03 18:06:59 volumio volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 03 18:06:59 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:06:59 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:00 volumio volumio[830]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 03 18:07:00 volumio volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 18:07:00 volumio volumio[830]: info: [1725386820140] CoreMusicLibrary::Adding element Last_100 Sep 03 18:07:00 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 18:07:00 volumio volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 18:07:00 volumio volumio[830]: info: [1725386820236] CoreMusicLibrary::Adding element Webradio Sep 03 18:07:00 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 18:07:00 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 18:07:00 volumio volumio[830]: info: Initializing BBC Radios Sep 03 18:07:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 18:07:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:01 volumio volumio[830]: info: Volumio Calling Home Sep 03 18:07:05 volumio volumio[830]: info: MPD Permissions set Sep 03 18:07:05 volumio volumio[830]: info: MPD Permissions set Sep 03 18:07:05 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 03 18:07:05 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:05 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:05 volumio volumio[830]: info: Volumio called home Sep 03 18:07:06 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:06 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:06 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:06 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:06 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:06 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:06 volumio volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 03 18:07:06 volumio volumio[830]: info: Permissions for /etc/hostapd/hostapd.conf Sep 03 18:07:06 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 03 18:07:06 volumio volumio[830]: info: Discovery: Restarting Advertising due to device name change Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 03 18:07:07 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:07 volumio systemd[1]: Starting Wireless Services... Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:07 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:09 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:09 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:09 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:09 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:09 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:09 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:09 volumio volumio[830]: info: Discovery: A device disappeared from network Sep 03 18:07:09 volumio volumio[830]: info: Discovery: Device volumio disappeared from network Sep 03 18:07:09 volumio volumio[830]: info: Starting Shairport Sync Sep 03 18:07:09 volumio volumio[830]: info: Starting Shairport Sync Sep 03 18:07:09 volumio volumio[830]: info: Starting Shairport Sync Sep 03 18:07:10 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 03 18:07:10 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:10 volumio volumio[830]: info: Discovery: A device disappeared from network Sep 03 18:07:10 volumio sudo[1421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 03 18:07:10 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 03 18:07:10 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 03 18:07:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:11 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 03 18:07:11 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:11 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:11 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:11 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 03 18:07:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 03 18:07:11 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 03 18:07:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:11 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39:3000 from 192.168.100.48 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 03 18:07:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:11 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 03 18:07:12 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 03 18:07:12 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 03 18:07:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 03 18:07:12 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:12 volumio volumio[830]: info: Shairport-Sync Started Sep 03 18:07:12 volumio volumio[830]: Error adding Membership: Error: addMembership EINVAL Sep 03 18:07:12 volumio volumio[830]: info: Discovery: Started advertising with name: Volumio Sep 03 18:07:13 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:13 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:13 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 03 18:07:13 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 03 18:07:13 volumio volumio[830]: info: Shairport-Sync Started Sep 03 18:07:13 volumio volumio[830]: info: Shairport-Sync Started Sep 03 18:07:13 volumio volumio-remote-updater[467]: No test mode Sep 03 18:07:13 volumio volumio-remote-updater[467]: No alpha test mode Sep 03 18:07:13 volumio volumio[830]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 03 18:07:13 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 03 18:07:14 volumio volumio[830]: info: Discovery: adding 15a95d90-5442-4066-abce-8eeddd8e7491 Sep 03 18:07:14 volumio volumio[830]: info: Discovery: Found device Volumio Sep 03 18:07:14 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:14 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:14 volumio volumio[830]: info: Discovery: this is already registered, 15a95d90-5442-4066-abce-8eeddd8e7491 Sep 03 18:07:14 volumio volumio[830]: info: Discovery: Found device Volumio Sep 03 18:07:14 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:14 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:15 volumio wireless.js[1406]: Cleaning previous... Sep 03 18:07:15 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 03 18:07:16 volumio systemd[1]: hostapd.service: Succeeded. Sep 03 18:07:16 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 03 18:07:16 volumio sudo[1443]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 03 18:07:16 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:16 volumio avahi-daemon[437]: Withdrawing address record for 192.168.211.1 on wlan0. Sep 03 18:07:16 volumio avahi-daemon[437]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 03 18:07:16 volumio avahi-daemon[437]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 03 18:07:16 volumio volumio[830]: info: Discovery: A device disappeared from network Sep 03 18:07:16 volumio sudo[1443]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:16 volumio volumio[830]: info: Discovery: Device volumio disappeared from network Sep 03 18:07:16 volumio sudo[1445]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 03 18:07:16 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:16 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:16 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:16 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:16 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:16 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:16 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:17 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:17 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:17 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:17 volumio volumio[830]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Sep 03 18:07:17 volumio volumio[830]: Terminated Sep 03 18:07:17 volumio volumio[830]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Sep 03 18:07:17 volumio volumio[830]: Terminated Sep 03 18:07:17 volumio volumio[830]: info: Completed starting Core Plugins Sep 03 18:07:17 volumio volumio[830]: info: ------------------------------------------- Sep 03 18:07:17 volumio volumio[830]: info: ----- MyVolumio plugins startup ---- Sep 03 18:07:17 volumio volumio[830]: info: ------------------------------------------- Sep 03 18:07:17 volumio volumio[830]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 03 18:07:17 volumio wireless.js[1406]: Stopped aP Sep 03 18:07:18 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 03 18:07:18 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:18 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:18 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 03 18:07:18 volumio sudo[1455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 03 18:07:18 volumio sudo[1455]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:18 volumio sudo[1455]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:18 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 03 18:07:18 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:18 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:19 volumio sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 03 18:07:19 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:19 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:19 volumio wireless.js[1406]: SETTING APPROPRIATE REG DOMAIN: PL Sep 03 18:07:19 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 03 18:07:19 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:19 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:20 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set PL Sep 03 18:07:20 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:20 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:20 volumio wireless.js[1406]: SUCCESSFULLY SET NEW REGDOMAIN: PL Sep 03 18:07:20 volumio sudo[1478]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 03 18:07:20 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:20 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:21 volumio sudo[1480]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 03 18:07:21 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:21 volumio mpd[1370]: Sep 03 18:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 03 18:07:21 volumio mpd[1370]: Sep 03 18:07 : exception: Database corrupted Sep 03 18:07:21 volumio systemd[1]: Started Music Player Daemon. Sep 03 18:07:21 volumio ntpd[538]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=118 secs Sep 03 18:07:21 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:21 volumio avahi-daemon[437]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 03 18:07:21 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 03 18:07:21 volumio avahi-daemon[437]: New relevant interface wlan0.IPv4 for mDNS. Sep 03 18:07:21 volumio avahi-daemon[437]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Sep 03 18:07:22 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 03 18:07:22 volumio hostapd[1489]: Configuration file: /etc/hostapd/hostapd.conf Sep 03 18:07:22 volumio hostapd[1489]: wlan0: Could not connect to kernel driver Sep 03 18:07:22 volumio hostapd[1489]: Using interface wlan0 with hwaddr b8:27:eb:47:d8:bd and ssid "Volumio-EAD45" Sep 03 18:07:22 volumio volumio[830]: error: MPD error: The expression evaluated to a falsy value: Sep 03 18:07:22 volumio volumio[830]: assert.ok(self.idling) Sep 03 18:07:22 volumio volumio[830]: error: The expression evaluated to a falsy value: Sep 03 18:07:22 volumio volumio[830]: assert.ok(self.idling) Sep 03 18:07:22 volumio volumio[830]: verbose: ControllerMpd::sendMpdCommand status Sep 03 18:07:22 volumio volumio[830]: info: Sep 03 18:07:22 volumio volumio[830]: ---------------------------- MPD announces state update: update Sep 03 18:07:22 volumio volumio[830]: info: ControllerMpd::getState Sep 03 18:07:22 volumio volumio[830]: verbose: ControllerMpd::sendMpdCommand status Sep 03 18:07:22 volumio volumio[830]: Upnp client error: Error: This socket has been ended by the other party Sep 03 18:07:22 volumio volumio[830]: Upnp client error: Error: This socket has been ended by the other party Sep 03 18:07:22 volumio hostapd[1489]: wlan0: interface state UNINITIALIZED->ENABLED Sep 03 18:07:22 volumio hostapd[1489]: wlan0: AP-ENABLED Sep 03 18:07:22 volumio volumio[830]: Upnp client error: Error: This socket has been ended by the other party Sep 03 18:07:22 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 03 18:07:22 volumio volumio[830]: verbose: ControllerMpd::sendMpdCommand status Sep 03 18:07:22 volumio volumio[830]: info: Sep 03 18:07:22 volumio volumio[830]: ---------------------------- MPD announces state update: update Sep 03 18:07:22 volumio volumio[830]: error: updateQueue error: null Sep 03 18:07:22 volumio volumio[830]: info: ControllerMpd::getState Sep 03 18:07:22 volumio volumio[830]: verbose: ControllerMpd::sendMpdCommand status Sep 03 18:07:22 volumio volumio[830]: info: sendMpdCommand status took 259 milliseconds Sep 03 18:07:22 volumio volumio[830]: verbose: ControllerMpd::parseState Sep 03 18:07:22 volumio iwconfig[1493]: Error for wireless request "Set Power Management" (8B2C) : Sep 03 18:07:22 volumio iwconfig[1493]: SET failed on device wlan0 ; Invalid argument. Sep 03 18:07:22 volumio systemd[1]: wireless.service: Succeeded. Sep 03 18:07:22 volumio systemd[1]: Started Wireless Services. Sep 03 18:07:22 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:23 volumio volumio[830]: info: Command Router : Notfying DB Updatefalse Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::Close All Modals sent Sep 03 18:07:23 volumio volumio[830]: info: sendMpdCommand status took 817 milliseconds Sep 03 18:07:23 volumio volumio[830]: info: sendMpdCommand status took 752 milliseconds Sep 03 18:07:23 volumio volumio[830]: info: sendMpdCommand status took 681 milliseconds Sep 03 18:07:23 volumio volumio[830]: verbose: ControllerMpd::parseState Sep 03 18:07:23 volumio volumio[830]: verbose: ControllerMpd::parseState Sep 03 18:07:23 volumio volumio[830]: info: Command Router : Notfying DB Updatefalse Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::Close All Modals sent Sep 03 18:07:23 volumio volumio[830]: verbose: ControllerMpd::parseState Sep 03 18:07:23 volumio volumio[830]: info: ControllerMpd::pushState Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::servicePushState Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 18:07:23 volumio volumio[830]: verbose: CURRENT POSITION 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::syncState stateService stop Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::syncState currentStatus stop Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: No code Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: ControllerMpd::pushState Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::servicePushState Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 18:07:23 volumio volumio[830]: verbose: CURRENT POSITION 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::syncState stateService stop Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::syncState currentStatus stop Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: No code Sep 03 18:07:23 volumio volumio[830]: info: CoreStateMachine::pushState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Sep 03 18:07:23 volumio volumio[830]: info: ------------------------------ 1137ms Sep 03 18:07:23 volumio volumio[830]: info: ------------------------------ 1154ms Sep 03 18:07:23 volumio volumio[830]: info: Discovery: adding 15a95d90-5442-4066-abce-8eeddd8e7491 Sep 03 18:07:23 volumio volumio[830]: info: Discovery: Found device Volumio Sep 03 18:07:23 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:23 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:24 volumio ntpd[538]: Listen normally on 5 wlan0 192.168.211.1:123 Sep 03 18:07:24 volumio ntpd[538]: new interface(s) found: waking up resolver Sep 03 18:07:24 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:24 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:24 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:24 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:24 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:24 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin bluetooth to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin multiroom to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin metavolumio to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin cd_controller to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 03 18:07:26 volumio volumio[830]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 03 18:07:32 volumio volumio[830]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 03 18:07:32 volumio volumio[830]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 03 18:07:32 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:32 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:32 volumio volumio[830]: info: Starting MyVolumio Remote Streaming Endpoints Sep 03 18:07:32 volumio volumio[830]: info: MyVolumio not started Sep 03 18:07:32 volumio volumio[830]: info: Initializing device activation check Sep 03 18:07:32 volumio volumio[830]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 03 18:07:32 volumio volumio[830]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 03 18:07:37 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:37 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:37 volumio volumio[830]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Sep 03 18:07:37 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:37 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:37 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:37 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:37 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:37 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:37 volumio volumio[830]: error: [MyVolumio PluginManager] The plugin music_service/streaming_services failed to load, setting it to stopped. Error: Error: UNKNOWN: unknown error, mkdir '/data/configuration/music_service/streaming_services' Sep 03 18:07:37 volumio volumio[830]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:38 volumio volumio[830]: info: Starting Streaming Service Transparent Proxy Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm node: checksumming directory block 0 Sep 03 18:07:38 volumio volumio[830]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 03 18:07:38 volumio volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 03 18:07:38 volumio volumio[830]: info: Streaming services startup Sep 03 18:07:38 volumio volumio[830]: info: Starting Streaming Daemon Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm rm: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm rm: checksumming directory block 0 Sep 03 18:07:38 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm rm: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm rm: checksumming directory block 0 Sep 03 18:07:38 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:38 volumio volumio[830]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 03 18:07:38 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370614: comm rm: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:38 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370614: comm rm: checksumming directory block 0 Sep 03 18:07:38 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Sep 03 18:07:39 volumio sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 18:07:39 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 03 18:07:39 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Sep 03 18:07:39 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Sep 03 18:07:39 volumio volumio[830]: info: Refreshing Cached IP Addresses Sep 03 18:07:39 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 03 18:07:39 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:40 volumio systemd[1]: upmpdcli.service: Main process exited, code=killed, status=15/TERM Sep 03 18:07:40 volumio systemd[1]: upmpdcli.service: Succeeded. Sep 03 18:07:40 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:40 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:40 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:40 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:40 volumio volumio[830]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 03 18:07:40 volumio sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:40 volumio sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:40 volumio sudo[1524]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:40 volumio volumio[830]: error: Cannot start Volumio Streaming Daemon Sep 03 18:07:40 volumio volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 03 18:07:40 volumio volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 03 18:07:41 volumio volumio[830]: Upnp client error: Error: This socket has been ended by the other party Sep 03 18:07:42 volumio volumio-remote-updater[467]: No test mode Sep 03 18:07:42 volumio volumio-remote-updater[467]: No alpha test mode Sep 03 18:07:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 03 18:07:42 volumio volumio[830]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 03 18:07:42 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 03 18:07:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:44 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:44 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:44 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:44 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:45 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:45 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:45 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:45 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:45 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39:3000 from 192.168.100.48 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 03 18:07:47 volumio smbd[1529]: [2024/09/03 18:07:47.024156, 0] ../source3/lib/util.c:493(reinit_after_fork) Sep 03 18:07:47 volumio kernel: EXT4-fs warning: 4 callbacks suppressed Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error: 4 callbacks suppressed Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio smbd[1529]: messaging_reinit() failed: NT_STATUS_ACCESS_DENIED Sep 03 18:07:47 volumio smbd[1529]: [2024/09/03 18:07:47.034098, 0] ../source3/smbd/server.c:1027(smbd_accept_connection) Sep 03 18:07:47 volumio smbd[1529]: reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1529]: [2024/09/03 18:07:47.034871, 0] ../source3/lib/util.c:816(smb_panic_s3) Sep 03 18:07:47 volumio smbd[1529]: PANIC (pid 1529): reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1529]: [2024/09/03 18:07:47.069077, 0] ../lib/util/fault.c:261(log_stack_trace) Sep 03 18:07:47 volumio smbd[1529]: BACKTRACE: 0 stack frames: Sep 03 18:07:47 volumio smbd[1529]: [2024/09/03 18:07:47.072093, 0] ../source3/lib/dumpcore.c:315(dump_core) Sep 03 18:07:47 volumio smbd[1529]: dumping core in /var/log/samba/cores/smbd Sep 03 18:07:47 volumio smbd[1529]: Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 18:07:47 volumio smbd[1530]: [2024/09/03 18:07:47.186144, 0] ../source3/lib/util.c:493(reinit_after_fork) Sep 03 18:07:47 volumio smbd[1530]: messaging_reinit() failed: NT_STATUS_ACCESS_DENIED Sep 03 18:07:47 volumio smbd[1530]: [2024/09/03 18:07:47.191577, 0] ../source3/smbd/server.c:1027(smbd_accept_connection) Sep 03 18:07:47 volumio smbd[1530]: reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1530]: [2024/09/03 18:07:47.192081, 0] ../source3/lib/util.c:816(smb_panic_s3) Sep 03 18:07:47 volumio smbd[1530]: PANIC (pid 1530): reinit_after_fork() failed Sep 03 18:07:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 18:07:47 volumio volumio[830]: info: Discovery: Getting this device information Sep 03 18:07:47 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:47 volumio smbd[1530]: [2024/09/03 18:07:47.231885, 0] ../lib/util/fault.c:261(log_stack_trace) Sep 03 18:07:47 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm cleanupd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm cleanupd: checksumming directory block 0 Sep 03 18:07:47 volumio smbd[1530]: BACKTRACE: 0 stack frames: Sep 03 18:07:47 volumio smbd[1530]: [2024/09/03 18:07:47.269805, 0] ../source3/lib/dumpcore.c:315(dump_core) Sep 03 18:07:47 volumio smbd[1530]: dumping core in /var/log/samba/cores/smbd Sep 03 18:07:47 volumio smbd[1530]: Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm cleanupd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm cleanupd: checksumming directory block 0 Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio smbd[1531]: [2024/09/03 18:07:47.421650, 0] ../source3/lib/util.c:493(reinit_after_fork) Sep 03 18:07:47 volumio smbd[1531]: messaging_reinit() failed: NT_STATUS_ACCESS_DENIED Sep 03 18:07:47 volumio smbd[1531]: [2024/09/03 18:07:47.427085, 0] ../source3/smbd/server.c:1027(smbd_accept_connection) Sep 03 18:07:47 volumio smbd[1531]: reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1531]: [2024/09/03 18:07:47.427686, 0] ../source3/lib/util.c:816(smb_panic_s3) Sep 03 18:07:47 volumio smbd[1531]: PANIC (pid 1531): reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1531]: [2024/09/03 18:07:47.437147, 0] ../lib/util/fault.c:261(log_stack_trace) Sep 03 18:07:47 volumio smbd[1531]: BACKTRACE: 0 stack frames: Sep 03 18:07:47 volumio smbd[1531]: [2024/09/03 18:07:47.459763, 0] ../source3/lib/dumpcore.c:315(dump_core) Sep 03 18:07:47 volumio smbd[1531]: dumping core in /var/log/samba/cores/smbd Sep 03 18:07:47 volumio smbd[1531]: Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm cleanupd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm cleanupd: checksumming directory block 0 Sep 03 18:07:47 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370528: comm smbd: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:47 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1695: inode #1370528: comm smbd: checksumming directory block 0 Sep 03 18:07:47 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 03 18:07:47 volumio smbd[1532]: [2024/09/03 18:07:47.587916, 0] ../source3/lib/util.c:493(reinit_after_fork) Sep 03 18:07:47 volumio smbd[1532]: messaging_reinit() failed: NT_STATUS_ACCESS_DENIED Sep 03 18:07:47 volumio smbd[1532]: [2024/09/03 18:07:47.593270, 0] ../source3/smbd/server.c:1027(smbd_accept_connection) Sep 03 18:07:47 volumio smbd[1532]: reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1532]: [2024/09/03 18:07:47.593770, 0] ../source3/lib/util.c:816(smb_panic_s3) Sep 03 18:07:47 volumio smbd[1532]: PANIC (pid 1532): reinit_after_fork() failed Sep 03 18:07:47 volumio smbd[1532]: [2024/09/03 18:07:47.613400, 0] ../lib/util/fault.c:261(log_stack_trace) Sep 03 18:07:47 volumio smbd[1532]: BACKTRACE: 0 stack frames: Sep 03 18:07:47 volumio smbd[1532]: [2024/09/03 18:07:47.618606, 0] ../source3/lib/dumpcore.c:315(dump_core) Sep 03 18:07:47 volumio smbd[1532]: dumping core in /var/log/samba/cores/smbd Sep 03 18:07:47 volumio smbd[1532]: Sep 03 18:07:48 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:48 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:48 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:48 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:48 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:48 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:48 volumio volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 03 18:07:49 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:49 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:49 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:49 volumio sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Sep 03 18:07:49 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:49 volumio volumio[830]: info: CorePlayQueue::getTrack 0 Sep 03 18:07:49 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:49 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 03 18:07:50 volumio sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:50 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:50 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:50 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:50 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:50 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:50 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 03 18:07:51 volumio sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:51 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:51 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:51 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:51 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:51 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:51 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 03 18:07:51 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:51 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 03 18:07:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 03 18:07:51 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 03 18:07:51 volumio systemd[1]: Failed to canonicalize path '/etc/systemd/system.control/upmpdcli.service.d': Bad message Sep 03 18:07:51 volumio systemd[1]: Failed to canonicalize path '/etc/systemd/system/upmpdcli.service.d': Bad message Sep 03 18:07:51 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:52 volumio volumio[830]: info: Upmpdcli Daemon Started Sep 03 18:07:52 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 18:07:52 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:52 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:52 volumio sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 18:07:52 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 03 18:07:52 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 18:07:52 volumio volumio[830]: verbose: New Socket.io Connection to 192.168.100.39 from 192.168.100.48 UA: Mozilla/5.0 (Linux; Android 13; moto g72 Build/T2SVS33.68-21-8-9; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 03 18:07:52 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Sep 03 18:07:52 volumio volumio[830]: STREAMING PROXY: Starting server on port 3245 Sep 03 18:07:52 volumio volumio[830]: Node JS runtime: 14 Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::volumioGetBrowseSources Sep 03 18:07:52 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 18:07:52 volumio kernel: EXT4-fs warning: 5 callbacks suppressed Sep 03 18:07:52 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:405: inode #1370531: comm node: No space for directory leaf checksum. Please run e2fsck -D. Sep 03 18:07:52 volumio kernel: EXT4-fs error: 5 callbacks suppressed Sep 03 18:07:52 volumio kernel: EXT4-fs error (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #1370531: comm node: Directory block failed checksum Sep 03 18:07:52 volumio volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 18:07:52 volumio volumio[830]: Error: UNKNOWN: unknown error, scandir '/data/plugins/' Sep 03 18:07:52 volumio volumio[830]: at Object.readdirSync (fs.js:1021:3) Sep 03 18:07:52 volumio volumio[830]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Sep 03 18:07:52 volumio volumio[830]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Sep 03 18:07:52 volumio volumio[830]: at myVolumio.identifyUserProperties (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:42317) Sep 03 18:07:52 volumio volumio[830]: at Timeout._onTimeout (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:2508) Sep 03 18:07:52 volumio volumio[830]: at listOnTimeout (internal/timers.js:554:17) Sep 03 18:07:52 volumio volumio[830]: at processTimers (internal/timers.js:497:7) { Sep 03 18:07:52 volumio volumio[830]: errno: -74, Sep 03 18:07:52 volumio volumio[830]: syscall: 'scandir', Sep 03 18:07:52 volumio volumio[830]: code: 'UNKNOWN', Sep 03 18:07:52 volumio volumio[830]: path: '/data/plugins/' Sep 03 18:07:52 volumio volumio[830]: } Sep 03 18:07:52 volumio volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 18:07:55 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 18:06 Sep 03 18:07:55 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"