-- Logs begin at Sat 2024-07-06 16:23:53 EDT, end at Sat 2024-07-06 16:59:01 EDT. -- Jul 06 16:58:36 volumio volumio[800]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jul 06 16:58:36 volumio volumio[800]: info: System name has changed, restarting Shairport Sync Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:58:36 volumio volumio[800]: xcb_connection_has_error() returned true Jul 06 16:58:36 volumio volumio[800]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 16:58:36 volumio volumio[800]: xcb_connection_has_error() returned true Jul 06 16:58:36 volumio volumio[800]: info: Updating MyVolumio device info Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:58:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 16:58:36 volumio volumio[800]: info: Discovery: Restarting Advertising due to device name change Jul 06 16:58:36 volumio sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jul 06 16:58:36 volumio sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:36 volumio sudo[1652]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:36 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party Jul 06 16:58:36 volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Hermes Jul 06 16:58:36 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:36 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:36 volumio volumio[800]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Hermes' Jul 06 16:58:36 volumio volumio[800]: Can't get device info: No such device Jul 06 16:58:36 volumio volumio[800]: info: Starting Shairport Sync Jul 06 16:58:37 volumio sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 06 16:58:37 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:37 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:37 volumio volumio[800]: info: Permissions for /etc/hosts set Jul 06 16:58:37 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 16:58:37 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:37 volumio volumio[800]: info: Hostname now is hermes Jul 06 16:58:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 16:58:37 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 16:58:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 16:58:37 volumio sudo[1664]: volumio : unable to resolve host volumio Jul 06 16:58:37 hermes sudo[1664]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Jul 06 16:58:37 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 16:58:37 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 06 16:58:37 hermes volumio[800]: info: New hostname set Jul 06 16:58:37 hermes volumio[800]: info: Permissions for /etc/avahi/services/volumio.service Jul 06 16:58:37 hermes volumio[800]: info: Avahi name changed to hermes Jul 06 16:58:37 hermes sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hostname hermes Jul 06 16:58:37 hermes systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 16:58:37 hermes sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:37 hermes sudo[1664]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:37 hermes sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Jul 06 16:58:37 hermes sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:37 hermes sudo[1667]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:37 hermes avahi-daemon[543]: Files changed, reloading. Jul 06 16:58:37 hermes avahi-daemon[543]: Service group file /services/volumio.service changed, reloading. Jul 06 16:58:37 hermes sudo[1661]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:37 hermes volumio[800]: info: Shairport-Sync Started Jul 06 16:58:37 hermes volumio[800]: info: Successfully Updated MyVolumio device Jul 06 16:58:37 hermes avahi-daemon[543]: Service "Hermes" (/services/volumio.service) successfully established. Jul 06 16:58:37 hermes volumio[800]: info: Discovery: A device disappeared from network Jul 06 16:58:37 hermes volumio[800]: info: Discovery: Device volumio disappeared from network Jul 06 16:58:41 hermes volumio[800]: info: Discovery: Started advertising with name: Hermes Jul 06 16:58:42 hermes ntpd[784]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 06 16:58:42 hermes volumio[800]: info: Discovery: adding 362b2df2-4e53-4b6a-b30d-dac209a51e2f Jul 06 16:58:42 hermes volumio[800]: info: Discovery: Found device Hermes Jul 06 16:58:42 hermes volumio[800]: info: CoreCommandRouter::volumioGetState Jul 06 16:58:42 hermes volumio[800]: info: CorePlayQueue::getTrack 0 Jul 06 16:58:46 hermes sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Jul 06 16:58:46 hermes sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:46 hermes sudo[1679]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:46 hermes volumio[800]: info: smb.conf Permissions set Jul 06 16:58:46 hermes sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Jul 06 16:58:46 hermes sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:46 hermes sudo[1681]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Jul 06 16:58:47 hermes sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:47 hermes sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 16:58:47 hermes sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:47 hermes nmbd[755]: [2024/07/06 16:58:47.079621, 0] ../source3/nmbd/nmbd.c:58(terminate) Jul 06 16:58:47 hermes nmbd[755]: Got SIGTERM: going down... Jul 06 16:58:47 hermes systemd[1]: Stopping Samba NMB Daemon... Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 16:58:47 hermes volumio[800]: info: Internal perms successfully set Jul 06 16:58:47 hermes sudo[1688]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes systemd[1]: nmbd.service: Succeeded. Jul 06 16:58:47 hermes systemd[1]: Stopped Samba NMB Daemon. Jul 06 16:58:47 hermes sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 16:58:47 hermes sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:47 hermes systemd[1]: Starting Samba NMB Daemon... Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 06 16:58:47 hermes sudo[1691]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 16:58:47 hermes sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 06 16:58:47 hermes sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:47 hermes sudo[1697]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes volumio[800]: info: Upmpdcli Daemon Started Jul 06 16:58:47 hermes nmbd[1699]: [2024/07/06 16:58:47.306621, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 06 16:58:47 hermes nmbd[1699]: started asyncdns process 1701 Jul 06 16:58:47 hermes nmbd[1699]: [2024/07/06 16:58:47.311675, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 16:58:47 hermes nmbd[1699]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 06 16:58:47 hermes systemd[1]: Started Samba NMB Daemon. Jul 06 16:58:47 hermes sudo[1685]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Jul 06 16:58:47 hermes sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 16:58:47 hermes systemd[1]: Stopping Samba SMB Daemon... Jul 06 16:58:47 hermes systemd[1]: smbd.service: Main process exited, code=killed, status=15/TERM Jul 06 16:58:47 hermes systemd[1]: smbd.service: Succeeded. Jul 06 16:58:47 hermes systemd[1]: Stopped Samba SMB Daemon. Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 06 16:58:47 hermes systemd[1]: Starting Samba SMB Daemon... Jul 06 16:58:47 hermes volumio[800]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 06 16:58:47 hermes winbindd[879]: [2024/07/06 16:58:47.679070, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Jul 06 16:58:47 hermes winbindd[879]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1637845562-264265575-328344243-514 Jul 06 16:58:47 hermes smbd[1707]: [2024/07/06 16:58:47.696704, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 16:58:47 hermes smbd[1707]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 06 16:58:47 hermes systemd[1]: Started Samba SMB Daemon. Jul 06 16:58:47 hermes sudo[1702]: pam_unix(sudo:session): session closed for user root Jul 06 16:58:47 hermes volumio[800]: info: SAMBA Restarted Jul 06 16:59:00 hermes volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Jul 06 16:59:01 hermes volumio[800]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Jul 06 16:59:01 hermes volumio[800]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 16:59:01 hermes volumio[800]: TypeError: Cannot read property 'then' of undefined Jul 06 16:59:01 hermes volumio[800]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Jul 06 16:59:01 hermes volumio[800]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2260:30) Jul 06 16:59:01 hermes volumio[800]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1435:26) Jul 06 16:59:01 hermes volumio[800]: at Socket.emit (events.js:315:20) Jul 06 16:59:01 hermes volumio[800]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 06 16:59:01 hermes volumio[800]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 06 16:59:01 hermes volumio[800]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 16:59:01 hermes sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-06 16:58 Jul 06 16:59:01 hermes sudo[1760]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"