-- Logs begin at Wed 2024-11-27 16:50:44 CST, end at Wed 2024-11-27 16:58:14 CST. -- Nov 27 16:57:05 volumio volumio[893]: info: volroon::Roon Core Paired: 192.168.2.210:9330 with ID of: 66172c63-26dc-4d6a-a759-1fec387ac1d8 Nov 27 16:57:05 volumio volumio[893]: verbose: volroon::State snapshot: {"status":"play","service":"volroon","title":"容易受伤的女人","artist":"王菲","album":"阿菲正传","albumart":"http://192.168.2.210:9330/api/image/0e257ffceb9774471e7175042df113cb","uri":"","trackType":"roon","seek":154000,"duration":256,"samplerate":"","bitdepth":"","bitrate":"","channels":2,"stream":false,"random":false,"repeat":false,"repeatSingle":false,"disableUiControls":false,"codec":"","repeatMode":"all"} Nov 27 16:57:05 volumio volumio[893]: info: volroon::pushState Nov 27 16:57:05 volumio volumio[893]: info: CoreCommandRouter::servicePushState Nov 27 16:57:05 volumio volumio[893]: info: CoreStateMachine::pushState Nov 27 16:57:05 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 16:57:05 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Nov 27 16:57:09 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:57:09 volumio volumio[893]: info: Listing playlists Nov 27 16:57:09 volumio volumio[893]: info: Listing playlists Nov 27 16:57:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 27 16:57:13 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 27 16:57:17 volumio sudo[3053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Nov 27 16:57:17 volumio sudo[3047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Nov 27 16:57:17 volumio sudo[3049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Nov 27 16:57:17 volumio sudo[3051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Nov 27 16:57:17 volumio sudo[3053]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 16:57:17 volumio sudo[3047]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 16:57:17 volumio volumio[893]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Nov 27 16:57:17 volumio sudo[3051]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 16:57:17 volumio sudo[3049]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 16:57:17 volumio sudo[3047]: pam_unix(sudo:session): session closed for user root Nov 27 16:57:18 volumio systemd[1]: Reloading. Nov 27 16:57:19 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:57:19 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. Nov 27 16:57:20 volumio systemd[1]: Created slice system-getty.slice. Nov 27 16:57:20 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Nov 27 16:57:20 volumio volumio[893]: info: touch_display: systemctl enable getty@tty1.service succeeded. Nov 27 16:57:20 volumio systemd[1]: Started Getty on tty1. Nov 27 16:57:20 volumio sudo[3049]: pam_unix(sudo:session): session closed for user root Nov 27 16:57:20 volumio volumio[893]: info: touch_display: systemctl start getty@tty1.service succeeded. Nov 27 16:57:20 volumio systemd[1]: Stopping Volumio Kiosk... Nov 27 16:57:20 volumio startx[1289]: xinit: connection to X server lost Nov 27 16:57:20 volumio startx[1289]: [1B blob data] Nov 27 16:57:22 volumio startx[1289]: waiting for X server to shut down .(II) Server terminated successfully (0). Closing log file. Nov 27 16:57:23 volumio startx[1289]: . Nov 27 16:57:23 volumio startx[1289]: xinit: unexpected signal 15 Nov 27 16:57:23 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Nov 27 16:57:23 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Nov 27 16:57:23 volumio systemd[1]: Stopped Volumio Kiosk. Nov 27 16:57:23 volumio sudo[3053]: pam_unix(sudo:session): session closed for user root Nov 27 16:57:23 volumio volumio[893]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Nov 27 16:57:23 volumio volumio[893]: info: Disabling plugin touch_display Nov 27 16:57:23 volumio volumio[893]: info: Done. Nov 27 16:57:29 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:57:29 volumio volumio[893]: info: Listing playlists Nov 27 16:57:29 volumio volumio[893]: info: Listing playlists Nov 27 16:57:32 volumio sshd[3124]: Accepted password for volumio from 192.168.2.117 port 53480 ssh2 Nov 27 16:57:32 volumio sshd[3124]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Nov 27 16:57:32 volumio systemd-logind[547]: New session 3 of user volumio. Nov 27 16:57:32 volumio systemd[1]: Started Session 3 of user volumio. Nov 27 16:57:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:57:44 volumio avahi-daemon[550]: avahi_normalize_name() failed. Nov 27 16:57:49 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:57:49 volumio volumio[893]: info: Listing playlists Nov 27 16:57:49 volumio volumio[893]: info: Listing playlists Nov 27 16:57:49 volumio systemd[1006]: Started D-Bus User Message Bus. Nov 27 16:57:52 volumio systemd[1006]: Starting Sound Service... Nov 27 16:57:52 volumio dbus-daemon[537]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.30' (uid=1000 pid=3339 comm="/usr/bin/pulseaudio --daemonize=no ") Nov 27 16:57:52 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 27 16:57:52 volumio dbus-daemon[537]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 27 16:57:52 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Successfully called chroot. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Successfully dropped privileges. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Successfully limited resources. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Running. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Canary thread running. Nov 27 16:57:52 volumio rtkit-daemon[3345]: Watchdog thread running. Nov 27 16:57:52 volumio dbus-daemon[537]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.31' (uid=0 pid=3345 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 27 16:57:52 volumio systemd[1]: Starting Authorization Manager... Nov 27 16:57:53 volumio polkitd[3349]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 27 16:57:53 volumio dbus-daemon[537]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 27 16:57:53 volumio systemd[1]: Started Authorization Manager. Nov 27 16:57:53 volumio avahi-daemon[550]: avahi_normalize_name() failed. Nov 27 16:57:58 volumio systemd[1006]: Started Sound Service. Nov 27 16:57:58 volumio bluetoothd[726]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSource Nov 27 16:57:58 volumio bluetoothd[726]: Endpoint registered: sender=:1.51 path=/MediaEndpoint/A2DPSink Nov 27 16:57:58 volumio pulseaudio[3339]: E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 27 16:57:59 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Nov 27 16:58:09 volumio pulseaudio[3339]: XIO: fatal IO error 11 (Resource temporarily unavailable) on X server ":0" Nov 27 16:58:09 volumio pulseaudio[3339]: after 12 requests (6 known processed) with 0 events remaining. Nov 27 16:58:10 volumio systemd[1006]: pulseaudio.service: Main process exited, code=exited, status=1/FAILURE Nov 27 16:58:10 volumio systemd[1006]: pulseaudio.service: Failed with result 'exit-code'. Nov 27 16:58:10 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 16:58:10 volumio systemd[1006]: pulseaudio.service: Service RestartSec=100ms expired, scheduling restart. Nov 27 16:58:10 volumio systemd[1006]: pulseaudio.service: Scheduled restart job, restart counter is at 1. Nov 27 16:58:10 volumio systemd[1006]: Stopped Sound Service. Nov 27 16:58:10 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 16:58:10 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Nov 27 16:58:10 volumio kernel: Bluetooth: RFCOMM socket layer initialized Nov 27 16:58:10 volumio kernel: Bluetooth: RFCOMM ver 1.11 Nov 27 16:58:10 volumio bluetoothd[726]: Endpoint unregistered: sender=:1.51 path=/MediaEndpoint/A2DPSource Nov 27 16:58:10 volumio systemd[1006]: Starting Sound Service... Nov 27 16:58:10 volumio bluetoothd[726]: Endpoint unregistered: sender=:1.51 path=/MediaEndpoint/A2DPSink Nov 27 16:58:10 volumio volumio-remote-updater[548]: [2024-11-27 16:58:10] [warning] got non-close frame while closing Nov 27 16:58:10 volumio volumio-remote-updater[548]: [2024-11-27 16:58:10] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Nov 27 16:58:10 volumio volumio[893]: TypeError: Cannot read property 'ping' of undefined Nov 27 16:58:10 volumio volumio[893]: at Timeout._onTimeout (/data/plugins/music_service/volroon/node_modules/node-roon-api/transport-websocket.js:26:21) Nov 27 16:58:10 volumio volumio[893]: at listOnTimeout (internal/timers.js:554:17) Nov 27 16:58:10 volumio volumio[893]: at processTimers (internal/timers.js:497:7) Nov 27 16:58:10 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 16:58:11 volumio pulseaudio[3446]: W: [pulseaudio] pid.c: Stale PID file, overwriting. Nov 27 16:58:11 volumio systemd[1006]: Started Sound Service. Nov 27 16:58:11 volumio bluetoothd[726]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSource Nov 27 16:58:11 volumio bluetoothd[726]: Endpoint registered: sender=:1.71 path=/MediaEndpoint/A2DPSink Nov 27 16:58:11 volumio pulseaudio[3446]: E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 27 16:58:13 volumio systemd[1]: mpd.service: Succeeded. Nov 27 16:58:13 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 16:58:13 volumio systemd[1]: Starting Music Player Daemon... Nov 27 16:58:13 volumio sudo[3461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 16:58:13 volumio sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 16:58:13 volumio sudo[3461]: pam_unix(sudo:session): session closed for user root Nov 27 16:58:14 volumio sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 16:57 Nov 27 16:58:14 volumio sudo[3468]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"