-- Logs begin at Sun 2024-09-01 23:37:57 CEST, end at Tue 2024-09-03 12:23:29 CEST. -- Sep 03 12:22:07 phts-np-01 volumiologrotate[736]: ls: cannot access '/var/log/log.wb-PHTS': No such file or directory Sep 03 12:22:07 phts-np-01 volumiologrotate[736]: ls: cannot access 'NP-01': No such file or directory Sep 03 12:22:07 phts-np-01 sshd[11384]: Connection closed by authenticating user volumio 192.168.0.12 port 57938 [preauth] Sep 03 12:22:11 phts-np-01 sshd[11437]: Connection closed by authenticating user volumio 192.168.0.12 port 57941 [preauth] Sep 03 12:22:38 phts-np-01 sshd[11444]: Accepted publickey for volumio from 192.168.0.12 port 57959 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:38 phts-np-01 sshd[11444]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:38 phts-np-01 systemd-logind[748]: New session 72 of user volumio. Sep 03 12:22:38 phts-np-01 systemd[1]: Started Session 72 of user volumio. Sep 03 12:22:38 phts-np-01 sshd[11453]: Received disconnect from 192.168.0.12 port 57959:11: disconnected by user Sep 03 12:22:38 phts-np-01 sshd[11453]: Disconnected from user volumio 192.168.0.12 port 57959 Sep 03 12:22:38 phts-np-01 sshd[11444]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:38 phts-np-01 systemd[1]: session-72.scope: Succeeded. Sep 03 12:22:38 phts-np-01 systemd-logind[748]: Session 72 logged out. Waiting for processes to exit. Sep 03 12:22:38 phts-np-01 systemd-logind[748]: Removed session 72. Sep 03 12:22:38 phts-np-01 sshd[11455]: Accepted publickey for volumio from 192.168.0.12 port 57960 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:38 phts-np-01 sshd[11455]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:38 phts-np-01 systemd-logind[748]: New session 73 of user volumio. Sep 03 12:22:38 phts-np-01 systemd[1]: Started Session 73 of user volumio. Sep 03 12:22:39 phts-np-01 sshd[11464]: Received disconnect from 192.168.0.12 port 57960:11: disconnected by user Sep 03 12:22:39 phts-np-01 sshd[11464]: Disconnected from user volumio 192.168.0.12 port 57960 Sep 03 12:22:39 phts-np-01 sshd[11455]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:39 phts-np-01 systemd[1]: session-73.scope: Succeeded. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Session 73 logged out. Waiting for processes to exit. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Removed session 73. Sep 03 12:22:39 phts-np-01 sshd[11466]: Accepted publickey for volumio from 192.168.0.12 port 57961 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:39 phts-np-01 sshd[11466]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:39 phts-np-01 systemd-logind[748]: New session 74 of user volumio. Sep 03 12:22:39 phts-np-01 systemd[1]: Started Session 74 of user volumio. Sep 03 12:22:39 phts-np-01 sshd[11475]: Received disconnect from 192.168.0.12 port 57961:11: disconnected by user Sep 03 12:22:39 phts-np-01 sshd[11475]: Disconnected from user volumio 192.168.0.12 port 57961 Sep 03 12:22:39 phts-np-01 sshd[11466]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:39 phts-np-01 systemd[1]: session-74.scope: Succeeded. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Session 74 logged out. Waiting for processes to exit. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Removed session 74. Sep 03 12:22:39 phts-np-01 sshd[11477]: Accepted publickey for volumio from 192.168.0.12 port 57962 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:39 phts-np-01 sshd[11477]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:39 phts-np-01 systemd-logind[748]: New session 75 of user volumio. Sep 03 12:22:39 phts-np-01 systemd[1]: Started Session 75 of user volumio. Sep 03 12:22:39 phts-np-01 sshd[11486]: Received disconnect from 192.168.0.12 port 57962:11: disconnected by user Sep 03 12:22:39 phts-np-01 sshd[11486]: Disconnected from user volumio 192.168.0.12 port 57962 Sep 03 12:22:39 phts-np-01 sshd[11477]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:39 phts-np-01 systemd[1]: session-75.scope: Succeeded. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Session 75 logged out. Waiting for processes to exit. Sep 03 12:22:39 phts-np-01 systemd-logind[748]: Removed session 75. Sep 03 12:22:39 phts-np-01 sshd[11488]: Accepted publickey for volumio from 192.168.0.12 port 57963 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:39 phts-np-01 sshd[11488]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:39 phts-np-01 systemd-logind[748]: New session 76 of user volumio. Sep 03 12:22:40 phts-np-01 systemd[1]: Started Session 76 of user volumio. Sep 03 12:22:40 phts-np-01 sshd[11497]: Received disconnect from 192.168.0.12 port 57963:11: disconnected by user Sep 03 12:22:40 phts-np-01 sshd[11497]: Disconnected from user volumio 192.168.0.12 port 57963 Sep 03 12:22:40 phts-np-01 sshd[11488]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:40 phts-np-01 systemd[1]: session-76.scope: Succeeded. Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Session 76 logged out. Waiting for processes to exit. Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Removed session 76. Sep 03 12:22:40 phts-np-01 sshd[11499]: Accepted publickey for volumio from 192.168.0.12 port 57964 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:40 phts-np-01 sshd[11499]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:40 phts-np-01 systemd-logind[748]: New session 77 of user volumio. Sep 03 12:22:40 phts-np-01 systemd[1]: Started Session 77 of user volumio. Sep 03 12:22:40 phts-np-01 sshd[11509]: Received disconnect from 192.168.0.12 port 57964:11: disconnected by user Sep 03 12:22:40 phts-np-01 sshd[11509]: Disconnected from user volumio 192.168.0.12 port 57964 Sep 03 12:22:40 phts-np-01 sshd[11499]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:40 phts-np-01 systemd[1]: session-77.scope: Succeeded. Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Session 77 logged out. Waiting for processes to exit. Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Removed session 77. Sep 03 12:22:40 phts-np-01 sshd[11511]: Accepted publickey for volumio from 192.168.0.12 port 57965 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:40 phts-np-01 sshd[11511]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:40 phts-np-01 systemd-logind[748]: New session 78 of user volumio. Sep 03 12:22:40 phts-np-01 systemd[1]: Started Session 78 of user volumio. Sep 03 12:22:40 phts-np-01 sshd[11521]: Received disconnect from 192.168.0.12 port 57965:11: disconnected by user Sep 03 12:22:40 phts-np-01 sshd[11521]: Disconnected from user volumio 192.168.0.12 port 57965 Sep 03 12:22:40 phts-np-01 sshd[11511]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Session 78 logged out. Waiting for processes to exit. Sep 03 12:22:40 phts-np-01 systemd[1]: session-78.scope: Succeeded. Sep 03 12:22:40 phts-np-01 systemd-logind[748]: Removed session 78. Sep 03 12:22:41 phts-np-01 sshd[11523]: Accepted publickey for volumio from 192.168.0.12 port 57966 ssh2: RSA SHA256:6WoJN4S6h6Z+DkMPSw4nBbP78MqS2SQ3wPiFqcOgRpg Sep 03 12:22:41 phts-np-01 sshd[11523]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Sep 03 12:22:41 phts-np-01 systemd-logind[748]: New session 79 of user volumio. Sep 03 12:22:41 phts-np-01 sshd[11439]: Connection closed by authenticating user volumio 192.168.0.12 port 57943 [preauth] Sep 03 12:22:41 phts-np-01 systemd[1]: Started Session 79 of user volumio. Sep 03 12:22:41 phts-np-01 sudo[11533]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl restart volumio Sep 03 12:22:41 phts-np-01 sudo[11533]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:41 phts-np-01 systemd[1]: Stopping Volumio Backend Module... Sep 03 12:22:41 phts-np-01 volumio-remote-updater[735]: [2024-09-03 12:22:41] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 03 12:22:41 phts-np-01 volumio-remote-updater[735]: [2024-09-03 12:22:41] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 03 12:22:41 phts-np-01 vtcs[4767]: [2024-09-03 12:22:41.229] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Sep 03 12:22:41 phts-np-01 systemd[1]: Started dynamicswap service. Sep 03 12:22:41 phts-np-01 systemd[1]: dynamicswap.service: Succeeded. Sep 03 12:22:41 phts-np-01 go-librespot[4583]: time="2024-09-03T12:22:41+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 03 12:22:41 phts-np-01 systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Sep 03 12:22:41 phts-np-01 systemd[1]: volumio.service: Succeeded. Sep 03 12:22:41 phts-np-01 systemd[1]: Stopped Volumio Backend Module. Sep 03 12:22:41 phts-np-01 systemd[1]: Started Volumio Backend Module. Sep 03 12:22:41 phts-np-01 sudo[11533]: pam_unix(sudo:session): session closed for user root Sep 03 12:22:41 phts-np-01 sshd[11532]: Received disconnect from 192.168.0.12 port 57966:11: disconnected by user Sep 03 12:22:41 phts-np-01 sshd[11532]: Disconnected from user volumio 192.168.0.12 port 57966 Sep 03 12:22:41 phts-np-01 sshd[11523]: pam_unix(sshd:session): session closed for user volumio Sep 03 12:22:41 phts-np-01 systemd[1]: session-79.scope: Succeeded. Sep 03 12:22:41 phts-np-01 systemd-logind[748]: Session 79 logged out. Waiting for processes to exit. Sep 03 12:22:41 phts-np-01 systemd-logind[748]: Removed session 79. Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ----- Volumio3 ---- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ----- System startup ---- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: MYVOLUMIO Environment detected Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Plugin folders cleanup Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning into folder /volumio/app/plugins/ Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category audio_interface Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category miscellanea Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category music_service Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category plugins.json Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category system_controller Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category user_interface Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning into folder /data/plugins/ Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category audio_interface Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category miscellanea Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category music_service Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category system_hardware Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Scanning category user_interface Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Plugin folders cleanup completed Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ----- Core plugins startup ---- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Loading plugins from folder /volumio/app/plugins/ Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Adding plugin upnp to MyMusic Plugins Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Loading plugins from folder /data/plugins/ Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Loading plugin "system"... Sep 03 12:22:42 phts-np-01 volumio[11542]: info: Loading plugin "appearance"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "network"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Refreshing Cached IP Addresses Sep 03 12:22:43 phts-np-01 sudo[11565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 12:22:43 phts-np-01 sudo[11565]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Plugin peppy_screensaver is not enabled Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "services"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "alsa_controller"... Sep 03 12:22:43 phts-np-01 sudo[11565]: pam_unix(sudo:session): session closed for user root Sep 03 12:22:43 phts-np-01 sudo[11567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 12:22:43 phts-np-01 sudo[11567]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 sudo[11567]: pam_unix(sudo:session): session closed for user root Sep 03 12:22:43 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "wizard"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "networkfs"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__MP3 at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:22:43 phts-np-01 sudo[11573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_MP3_ /mnt/NAS/HOME-NAS__MP3 Sep 03 12:22:43 phts-np-01 sudo[11573]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 sudo[11575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_FLAC_ /mnt/NAS/HOME-NAS__FLAC Sep 03 12:22:43 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__Torrents at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:22:43 phts-np-01 sudo[11575]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Starting Udev Watcher for removable devices Sep 03 12:22:43 phts-np-01 sudo[11585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Download /mnt/NAS/HOME-NAS__Download Sep 03 12:22:43 phts-np-01 sudo[11585]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 sudo[11581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_APE_ /mnt/NAS/HOME-NAS__APE Sep 03 12:22:43 phts-np-01 sudo[11581]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Ignoring mount for partition: boot Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Ignoring mount for partition: volumio Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Ignoring mount for partition: volumio_data Sep 03 12:22:43 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "volumio_command_line_client"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Plugin upnp is not enabled Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "my_music"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "mpd"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Plugin upnp_browser is not enabled Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "alarm-clock"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Plugin airplay_emulation is not enabled Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "last_100"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "webradio"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "i2s_dacs"... Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "volumiodiscovery"... Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** For more information see Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 12:22:43 phts-np-01 volumio[11542]: *** WARNING *** For more information see Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** For more information see Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 03 12:22:43 phts-np-01 node[11542]: *** WARNING *** For more information see Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Discovery: Started advertising with name: PHTS NP-01 Sep 03 12:22:43 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 12:22:43 phts-np-01 volumio[11542]: info: Loading plugin "spop"... Sep 03 12:22:44 phts-np-01 volumio[11542]: info: Loading plugin "now_playing"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "outputs"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "albumart"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Plugin example_plugin is not enabled Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "inputs"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "updater_comm"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Plugin mpdemulation is not enabled Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "rest_api"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "websocket"... Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Starting Socket.io Server version 2.3.0 Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "fusiondsp"... Sep 03 12:22:45 phts-np-01 volumio[11542]: Forking 3 albumart workers Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Applying required configuration parameters for plugin fusiondsp Sep 03 12:22:45 phts-np-01 volumio[11542]: info: Loading plugin "podcast"... Sep 03 12:22:46 phts-np-01 volumio[11542]: info: ControllerPodcast::constructor Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Loading plugin "gpio_control"... Sep 03 12:22:46 phts-np-01 volumio-remote-updater[735]: [2024-09-03 12:22:46] [connect] Successful connection Sep 03 12:22:46 phts-np-01 volumio[11542]: Starting albumart workers Sep 03 12:22:46 phts-np-01 volumio[11542]: Starting albumart workers Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Applying required configuration parameters for plugin gpio_control Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Loading plugin "ir_controller"... Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Applying required configuration parameters for plugin ir_controller Sep 03 12:22:46 phts-np-01 volumio[11542]: Starting albumart workers Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Plugin music_services_shield is not enabled Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Loading plugin "peppymeterbasic"... Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Plugin peppyspectrum is not enabled Sep 03 12:22:46 phts-np-01 volumio[11542]: info: Loading plugin "touch_display"... Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Applying required configuration parameters for plugin touch_display Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Loading i18n strings for locale ru Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Sep 03 12:22:47 phts-np-01 volumio[11542]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:22:47 phts-np-01 volumio[11542]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:22:47 phts-np-01 volumio[11542]: Updating browse sources language Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: Express server listening on port 3000 Sep 03 12:22:47 phts-np-01 volumio[11542]: [Metrics] WebUI: 5s 333.36ms Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::resetVolumioState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::getCurrentVolume Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioRetrievevolume Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102:3000 from 192.168.0.132 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 3 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102:3000 from 192.168.0.132 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 03 12:22:47 phts-np-01 volumio-remote-updater[735]: [2024-09-03 12:22:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725358966 101 Sep 03 12:22:47 phts-np-01 volumio[11542]: 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: 7 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 03 12:22:47 phts-np-01 volumio[11542]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CALLMETHOD: system_controller system enableLiveLog true Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Launching a new LiveLog session Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 03 12:22:47 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 03 12:22:47 phts-np-01 volumio[11542]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Setting Device type: Raspberry PI Sep 03 12:22:47 phts-np-01 volumio[11542]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 03 12:22:47 phts-np-01 volumio[11542]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: adding 3801fca6-54df-4dad-b938-115022129ad9 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: Found device Volumio dev Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: Connecting to remote: 192.168.0.132 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: adding fa4041f9-84df-41a0-a145-e76c2b9dc2e7 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: Found device PHTS NP-01 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:47 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:47 phts-np-01 volumio[11542]: info: Discovery: Connected to remote: 192.168.0.132 Sep 03 12:22:48 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__MP3 at system boot, trial number 2 ,retrying in 5 seconds Sep 03 12:22:48 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__Torrents at system boot, trial number 2 ,retrying in 5 seconds Sep 03 12:22:48 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 03 12:22:48 phts-np-01 volumio[11542]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 03 12:22:48 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 03 12:22:48 phts-np-01 volumio[11542]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 03 12:22:49 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 03 12:22:49 phts-np-01 volumio[11542]: error: Failed callmethod call: TypeError: Cannot read property 'has' of undefined Sep 03 12:22:49 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:49 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:49 phts-np-01 sudo[11573]: pam_unix(sudo:session): session closed for user root Sep 03 12:22:49 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__MP3 at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:22:49 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:22:49 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:22:49 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:22:51 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.111 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 11 Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:22:51 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 03 12:22:51 phts-np-01 volumio[11542]: info: Received Get System Info Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:22:51 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:22:51 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:22:52 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:22:52 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:22:52 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:22:53 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__MP3 at system boot, trial number 3 ,retrying in 5 seconds Sep 03 12:22:53 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__Torrents at system boot, trial number 3 ,retrying in 5 seconds Sep 03 12:22:54 phts-np-01 sudo[11659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_MP3_ /mnt/NAS/HOME-NAS__MP3 Sep 03 12:22:54 phts-np-01 sudo[11659]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:22:56 phts-np-01 sudo[11575]: pam_unix(sudo:session): session closed for user root Sep 03 12:22:56 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__FLAC at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:22:56 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:22:56 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:22:56 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Download Sep 03 12:22:58 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__MP3 at system boot, trial number 4 ,retrying in 5 seconds Sep 03 12:22:58 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-PC__Torrents at system boot, trial number 4 ,retrying in 5 seconds Sep 03 12:23:01 phts-np-01 sudo[11666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 03 12:23:01 phts-np-01 sudo[11666]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:01 phts-np-01 sudo[11666]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:01 phts-np-01 sudo[11669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 03 12:23:01 phts-np-01 sudo[11669]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:01 phts-np-01 sudo[11669]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:01 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 12 Sep 03 12:23:01 phts-np-01 sudo[11672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_FLAC_ /mnt/NAS/HOME-NAS__FLAC Sep 03 12:23:01 phts-np-01 sudo[11672]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetVisibleSources Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 03 12:23:01 phts-np-01 volumio[11542]: info: Received Get System Info Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:23:01 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 03 12:23:01 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:23:02 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:02 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:02 phts-np-01 sudo[11585]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:02 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:23:02 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:23:02 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:23:02 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__Download at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:23:02 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:23:02 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:23:02 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:23:03 phts-np-01 volumio[11542]: info: Listing playlists Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 03 12:23:03 phts-np-01 volumio[11542]: info: Received Get System Info Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:23:03 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 03 12:23:03 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 03 12:23:03 phts-np-01 volumio[11542]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 03 12:23:03 phts-np-01 volumio[11542]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 03 12:23:04 phts-np-01 volumio[11542]: info: Received Get System Info Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:23:04 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:04 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:05 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 03 12:23:06 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:06 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:07 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 03 12:23:07 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:23:07 phts-np-01 sudo[11678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Download /mnt/NAS/HOME-NAS__Download Sep 03 12:23:07 phts-np-01 sudo[11678]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:07 phts-np-01 volumiologrotate[736]: ls: cannot access '/var/log/log.wb-PHTS': No such file or directory Sep 03 12:23:07 phts-np-01 volumiologrotate[736]: ls: cannot access 'NP-01': No such file or directory Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::Close All Modals sent Sep 03 12:23:08 phts-np-01 sudo[11581]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__APE at system boot, trial number 1 ,retrying in 5 seconds Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Completed loading Core Plugins Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Preparing to generate the ALSA configuration file Sep 03 12:23:08 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:23:08 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:23:08 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:23:08 phts-np-01 volumio[11542]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 12:23:08 phts-np-01 volumio[11542]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Reading ALSA contributions from plugins. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Asound.conf file unchanged, so no further update is needed Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Output device has changed, restarting MPD Sep 03 12:23:08 phts-np-01 sudo[11733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 12:23:08 phts-np-01 sudo[11733]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:08 phts-np-01 sudo[11733]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:08 phts-np-01 volumio[11542]: info: ___________ START PLUGINS ___________ Sep 03 12:23:08 phts-np-01 volumio[11542]: info: ControllerMpd::onStart: Initializing MPD Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Creating MPD Configuration file Sep 03 12:23:08 phts-np-01 sudo[11737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 12:23:08 phts-np-01 sudo[11737]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:08 phts-np-01 systemd[1]: Stopping Music Player Daemon... Sep 03 12:23:08 phts-np-01 sudo[11740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 12:23:08 phts-np-01 sudo[11740]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:08 phts-np-01 systemd[1]: mpd.service: Succeeded. Sep 03 12:23:08 phts-np-01 systemd[1]: Stopped Music Player Daemon. Sep 03 12:23:08 phts-np-01 sudo[11740]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 12:23:08 phts-np-01 volumio[11542]: info: [1725358988745] CoreMusicLibrary::Adding element Last_100 Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 12:23:08 phts-np-01 volumio[11542]: info: [1725358988755] CoreMusicLibrary::Adding element Webradio Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:23:08 phts-np-01 sudo[11744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 12:23:08 phts-np-01 sudo[11744]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Initializing BBC Radios Sep 03 12:23:08 phts-np-01 systemd[1]: Starting Music Player Daemon... Sep 03 12:23:08 phts-np-01 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 03 12:23:08 phts-np-01 systemd[1]: mpd.service: Succeeded. Sep 03 12:23:08 phts-np-01 systemd[1]: Stopped Music Player Daemon. Sep 03 12:23:08 phts-np-01 systemd[1]: Starting Music Player Daemon... Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Creating Spotify config file Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:08 phts-np-01 sudo[11757]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 12:23:08 phts-np-01 sudo[11757]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:08 phts-np-01 volumio[11542]: info: [now-playing] ConfigUpdater: config is up to date. Sep 03 12:23:08 phts-np-01 sudo[11757]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Loading i18n strings for locale ru Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:08 phts-np-01 volumio[11542]: info: FusionDsp - mixtype--------------------- None Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 12:23:08 phts-np-01 volumio[11542]: info: [1725358988938] CoreMusicLibrary::Adding element Podcast Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:23:08 phts-np-01 volumio[11542]: Cannot find translation for sourcePodcast Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Loading i18n strings for locale ru Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Loading i18n strings for locale ru Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Sep 03 12:23:08 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:23:08 phts-np-01 volumio[11542]: info: Volumio Calling Home Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Preparing to generate the ALSA configuration file Sep 03 12:23:09 phts-np-01 sudo[11770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Sep 03 12:23:09 phts-np-01 sudo[11770]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 sudo[11770]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:09 phts-np-01 sudo[11773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Sep 03 12:23:09 phts-np-01 sudo[11773]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 sudo[11777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Sep 03 12:23:09 phts-np-01 sudo[11777]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 sudo[11773]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:09 phts-np-01 sudo[11778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 03 12:23:09 phts-np-01 sudo[11778]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 systemd[1]: Reloading. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: [now-playing] Fetching API key... Sep 03 12:23:09 phts-np-01 volumio[11542]: info: [now-playing] App is listening on port 4004. Sep 03 12:23:09 phts-np-01 volumio[11542]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Sep 03 12:23:09 phts-np-01 volumio[11542]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 12:23:09 phts-np-01 volumio[11542]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Reading ALSA contributions from plugins. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: touch_display: No backlight interface detected. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:09 phts-np-01 volumio[11542]: info: touch_display: systemctl stop getty@tty1.service succeeded. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: MPD Permissions set Sep 03 12:23:09 phts-np-01 volumio[11542]: info: MPD Permissions set Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Spotify config file written Sep 03 12:23:09 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Sep 03 12:23:09 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Volumio called home Sep 03 12:23:09 phts-np-01 sudo[11795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 03 12:23:09 phts-np-01 sudo[11795]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:09 phts-np-01 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 12:23:09 phts-np-01 volumio[11542]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 03 12:23:09 phts-np-01 sudo[11806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Sep 03 12:23:09 phts-np-01 sudo[11806]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:09 phts-np-01 sudo[11806]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:09 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:09 phts-np-01 volumio[11542]: info: ir_controller: LIRC correctly updated. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Sep 03 12:23:09 phts-np-01 volumio[11542]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Asound.conf file unchanged, so no further update is needed Sep 03 12:23:09 phts-np-01 volumio[11542]: info: Output device has changed, restarting MPD Sep 03 12:23:10 phts-np-01 sudo[11809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 12:23:10 phts-np-01 sudo[11809]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:10 phts-np-01 sudo[11809]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Preparing to generate the ALSA configuration file Sep 03 12:23:10 phts-np-01 sudo[11814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 12:23:10 phts-np-01 sudo[11814]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:10 phts-np-01 volumio[11542]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 12:23:10 phts-np-01 volumio[11542]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Reading ALSA contributions from plugins. Sep 03 12:23:10 phts-np-01 volumio[11542]: info: MPD Permissions set Sep 03 12:23:10 phts-np-01 volumio[11542]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 12:23:10 phts-np-01 volumio[11542]: SPOTIFY: BQDGZchgxsBhMKB7AeqeHUSgjwgaxSPWTLpYB4RrGq2ZMZsQlvyVC8FNPxk2ze_HwqQZovXwIyIsz959a4MZ2jT1p-SDQQXe9p4i6_GZNm5WjECv8aSLl48U0uUNxhgdFnmkqtpcchku5JI_lNvETPfDJ8rt6xF4tC6N38vHpA-fdQhr02fpLkL6o4EsqXsQ-2leOpA_8u7bsciG6I99DD5oJdPK_oIcrpQKqwZbtnZZP342_gNMiuu6OFBLNIi2zxO7XrNq2S8SN3Y Sep 03 12:23:10 phts-np-01 volumio[11542]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 03 12:23:10 phts-np-01 volumio[11542]: info: New Spotify access token = BQDGZchgxsBhMKB7AeqeHUSgjwgaxSPWTLpYB4RrGq2ZMZsQlvyVC8FNPxk2ze_HwqQZovXwIyIsz959a4MZ2jT1p-SDQQXe9p4i6_GZNm5WjECv8aSLl48U0uUNxhgdFnmkqtpcchku5JI_lNvETPfDJ8rt6xF4tC6N38vHpA-fdQhr02fpLkL6o4EsqXsQ-2leOpA_8u7bsciG6I99DD5oJdPK_oIcrpQKqwZbtnZZP342_gNMiuu6OFBLNIi2zxO7XrNq2S8SN3Y Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Asound.conf file unchanged, so no further update is needed Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Output device has changed, restarting MPD Sep 03 12:23:10 phts-np-01 sudo[11821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 12:23:10 phts-np-01 sudo[11821]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:10 phts-np-01 sudo[11821]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:10 phts-np-01 sudo[11824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 12:23:10 phts-np-01 sudo[11824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:10 phts-np-01 systemd[1]: Started Music Player Daemon. Sep 03 12:23:10 phts-np-01 sudo[11744]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:10 phts-np-01 sudo[11737]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:10 phts-np-01 sudo[11777]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:10 phts-np-01 systemd[1]: Reloading. Sep 03 12:23:10 phts-np-01 volumio[11542]: info: MPD Permissions set Sep 03 12:23:10 phts-np-01 volumio[11542]: info: touch_display: systemctl disable getty@tty1.service succeeded. Sep 03 12:23:10 phts-np-01 volumio[11542]: error: MPD error: The expression evaluated to a falsy value: Sep 03 12:23:10 phts-np-01 volumio[11542]: assert.ok(self.idling) Sep 03 12:23:10 phts-np-01 volumio[11542]: error: The expression evaluated to a falsy value: Sep 03 12:23:10 phts-np-01 volumio[11542]: assert.ok(self.idling) Sep 03 12:23:10 phts-np-01 volumio[11542]: info: MPD running with PID11762 Sep 03 12:23:10 phts-np-01 volumio[11542]: ,establishing connection Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:10 phts-np-01 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 12:23:10 phts-np-01 volumio[11542]: info: [now-playing] OpenWeatherMap API key obtained. Sep 03 12:23:10 phts-np-01 volumio[11542]: error: updateQueue error: null Sep 03 12:23:10 phts-np-01 volumio[11542]: error: updateQueue error: null Sep 03 12:23:10 phts-np-01 volumio[11542]: SPOTIFY: User informations: {"display_name":"Phil","external_urls":{"spotify":"https://open.spotify.com/user/y1cudeljhoqy5ghfq2ebg0cig"},"href":"https://api.spotify.com/v1/users/y1cudeljhoqy5ghfq2ebg0cig","id":"y1cudeljhoqy5ghfq2ebg0cig","images":[{"url":"https://i.scdn.co/image/ab67757000003b8232361a841658e685ec827729","height":64,"width":64},{"url":"https://i.scdn.co/image/ab6775700000ee8532361a841658e685ec827729","height":300,"width":300}],"type":"user","uri":"spotify:user:y1cudeljhoqy5ghfq2ebg0cig","followers":{"href":null,"total":0},"country":"PL","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"phil.tsarik@gmail.com"} Sep 03 12:23:10 phts-np-01 volumio[11542]: info: Spotify Successfully logged in Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 03 12:23:10 phts-np-01 volumio[11542]: info: [1725358990739] CoreMusicLibrary::Adding element Spotify Sep 03 12:23:10 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 03 12:23:10 phts-np-01 volumio[11542]: Cannot find translation for sourcePodcast Sep 03 12:23:10 phts-np-01 volumio[11542]: Cannot find translation for sourceSpotify Sep 03 12:23:10 phts-np-01 volumio[11542]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 12:23:10 phts-np-01 volumio[11542]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 03 12:23:10 phts-np-01 volumio[11542]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 03 12:23:11 phts-np-01 volumio[11542]: info: camilladsp spawned new process with pid 11857, instance 1, run: true Sep 03 12:23:11 phts-np-01 volumio[11542]: info: camilladsp service started and running in background, instance 1 Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:11 phts-np-01 kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Sep 03 12:23:11 phts-np-01 volumio[11542]: cannot open device 'volumioHw': Device or resource busy Sep 03 12:23:11 phts-np-01 volumio[11542]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 03 12:23:11 phts-np-01 volumio[11542]: cannot open device 'volumioHw': Device or resource busy Sep 03 12:23:11 phts-np-01 sudo[11778]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp loaded Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:11 phts-np-01 systemd[1]: Stopping Music Player Daemon... Sep 03 12:23:11 phts-np-01 systemd[1]: Stopping go-librespot Daemon... Sep 03 12:23:11 phts-np-01 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 03 12:23:11 phts-np-01 systemd[1]: go-librespot-daemon.service: Succeeded. Sep 03 12:23:11 phts-np-01 systemd[1]: Stopped go-librespot Daemon. Sep 03 12:23:11 phts-np-01 systemd[1]: Started go-librespot Daemon. Sep 03 12:23:11 phts-np-01 sudo[11795]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 go-librespot[11869]: Librespot-go daemon starting... Sep 03 12:23:11 phts-np-01 systemd[1]: mpd.service: Succeeded. Sep 03 12:23:11 phts-np-01 systemd[1]: Stopped Music Player Daemon. Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 03 12:23:11 phts-np-01 volumio[11542]: info: Adding Signal Path Element [object Object] Sep 03 12:23:11 phts-np-01 volumio[11542]: info: Adding fusiondspeq DSP Signal Path Element Sep 03 12:23:11 phts-np-01 sudo[11868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 03 12:23:11 phts-np-01 sudo[11868]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp - ---- installed callbackRead Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 03 12:23:11 phts-np-01 systemd[1]: Starting Music Player Daemon... Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp - else 1 -2.5 Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=info msg="generated new device id: 629866b0c93ff163fc8f6f6460940f15fe088f3a" Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="stored credentials found for y1cudeljhoqy5ghfq2ebg0cig" Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:23:11 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:11 phts-np-01 sudo[11868]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:11 phts-np-01 sudo[11876]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 12:23:11 phts-np-01 sudo[11876]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:11 phts-np-01 sudo[11876]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: systemctl daemon-reload succeeded. Sep 03 12:23:11 phts-np-01 sudo[11884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 03 12:23:11 phts-np-01 sudo[11884]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:11 phts-np-01 sudo[11884]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 sudo[11887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Sep 03 12:23:11 phts-np-01 sudo[11887]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="fetched new accesspoints: [ap2-gew4.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gew4.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gue1.spotify.com:443 ap2-gae2.spotify.com:80]" Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 03 12:23:11 phts-np-01 lircd[4604]: lircd-0.10.1[4604]: Notice: caught signal Sep 03 12:23:11 phts-np-01 lircd-0.10.1[4604]: Notice: caught signal Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="zeroconf server listening on port 41101" Sep 03 12:23:11 phts-np-01 systemd[1]: Stopping Flexible IR remote input/output application support... Sep 03 12:23:11 phts-np-01 systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Sep 03 12:23:11 phts-np-01 systemd[1]: lircd.service: Succeeded. Sep 03 12:23:11 phts-np-01 systemd[1]: Stopped Flexible IR remote input/output application support. Sep 03 12:23:11 phts-np-01 systemd[1]: irexec.service: Succeeded. Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: Volumio Kiosk started. Sep 03 12:23:11 phts-np-01 systemd[1]: Starting lircd(8) initialization helper tool... Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=debug msg="obtained new client token: AABdFOwPv+nZg/gBVGxYCRIldnGZohmihuq7GhAhUHhTk8i2yDkewiCITvVSSc+4CVjiRFwehaQcF4fSvB/g4eUvbPt1P2dTEsG3g35PiaDCc7xJsS5VSsXO1deBa14D3B7cVKiZHvNe0IfiqzjtnQHskQcelQTUzk6hg91v5o4DS0MGbRvWDcDPnIW4C+UsfLJWsg8g90JzUnoyYeZN0M158SIFUd1wpFK1jhjtRfMcW3k2k1sJtxblLg==" Sep 03 12:23:11 phts-np-01 volumio[11542]: info: Completed starting Core Plugins Sep 03 12:23:11 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:23:11 phts-np-01 volumio[11542]: info: ----- MyVolumio plugins startup ---- Sep 03 12:23:11 phts-np-01 volumio[11542]: info: ------------------------------------------- Sep 03 12:23:11 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:11 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Sep 03 12:23:11 phts-np-01 volumio[11542]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 03 12:23:11 phts-np-01 go-librespot[11869]: time="2024-09-03T12:23:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 34.158.1.133:4070: connect: connection refused" Sep 03 12:23:11 phts-np-01 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 03 12:23:11 phts-np-01 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: X display number found: 0 Sep 03 12:23:11 phts-np-01 lircd-setup[11889]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Sep 03 12:23:11 phts-np-01 lircd-setup[11889]: parser = configparser.SafeConfigParser() Sep 03 12:23:11 phts-np-01 systemd[1]: lircd-setup.service: Succeeded. Sep 03 12:23:11 phts-np-01 systemd[1]: Started lircd(8) initialization helper tool. Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 03 12:23:11 phts-np-01 systemd[1]: Starting Flexible IR remote input/output application support... Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: lircd: Opening log, level: Info Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Version: lircd 0.10.1 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: System info: Linux phts-np-01 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l GNU/Linux Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: X display number found: 0 Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:11 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: Initial device: /dev/lirc0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: [lirc] protocol is enabled Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: Initial device: /dev/lirc0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: [lirc] protocol is enabled Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: driver: default Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: output: /var/run/lirc/lircd Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Info: lircd: Opening log, level: Info Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: nodaemon: 1 Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Notice: Using systemd fd Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Warning: Running as root Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Info: Using remote: Yamaha_RAS13. Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Info: Using remote: Samsung_AA59. Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: logfile: syslog Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: immediate-init: 0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: permission: 666 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: driver-options: Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: listen: 0 Sep 03 12:23:11 phts-np-01 systemd[1]: Started Flexible IR remote input/output application support. Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: connect: (null) Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: userelease: 0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: effective_user: (null) Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: release_suffix: _EVUP Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: allow_simulate: 0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: repeat_max: 600 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Options: dynamic_codes: (null) Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Current driver: default Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Driver API version: 3 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Driver version: 0.10.0 Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: lircd: Opening log, level: Info Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: Using systemd fd Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Warning: Running as root Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: Using remote: Yamaha_RAS13. Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: Using remote: Samsung_AA59. Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: lircd(default) ready, using /var/run/lirc/lircd Sep 03 12:23:11 phts-np-01 sudo[11887]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 volumio[11542]: info: ir_controller: systemctl restart lircd.service succeeded. Sep 03 12:23:11 phts-np-01 sudo[11905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Sep 03 12:23:11 phts-np-01 sudo[11905]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:11 phts-np-01 volumio[11542]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 12:23:11 phts-np-01 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Sep 03 12:23:11 phts-np-01 sudo[11905]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Notice: accepted new client on /var/run/lirc/lircd Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Notice: accepted new client on /var/run/lirc/lircd Sep 03 12:23:11 phts-np-01 lircd[11895]: lircd-0.10.1[11895]: Info: [lirc] protocol is enabled Sep 03 12:23:11 phts-np-01 lircd-0.10.1[11895]: Info: [lirc] protocol is enabled Sep 03 12:23:11 phts-np-01 volumio[11542]: info: ir_controller: systemctl restart irexec.service succeeded. Sep 03 12:23:12 phts-np-01 systemd[1]: Started Music Player Daemon. Sep 03 12:23:12 phts-np-01 sudo[11814]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:12 phts-np-01 sudo[11824]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:12 phts-np-01 volumio[11542]: error: MPD error: The expression evaluated to a falsy value: Sep 03 12:23:12 phts-np-01 volumio[11542]: assert.ok(self.idling) Sep 03 12:23:12 phts-np-01 volumio[11542]: error: The expression evaluated to a falsy value: Sep 03 12:23:12 phts-np-01 volumio[11542]: assert.ok(self.idling) Sep 03 12:23:12 phts-np-01 volumio[11542]: error: updateQueue error: null Sep 03 12:23:13 phts-np-01 sudo[11913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_APE_ /mnt/NAS/HOME-NAS__APE Sep 03 12:23:13 phts-np-01 sudo[11913]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:14 phts-np-01 volumio[11542]: info: go-librespot daemon successfully initialized Sep 03 12:23:14 phts-np-01 volumio[11542]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 03 12:23:14 phts-np-01 volumio[11542]: info: PeppyMeterBasic ---list is random,dash,Radiotehnika МП-7201 (partial),Radiotehnika У-7101 (indicators only),Radiotehnika МП-7201 (1 bar/no counter/no album art),Radiotehnika МП-7201 (2 bars/no counter/no album art),Radiotehnika МП-7201 (remaining time/album art - top),Radiotehnika МП-7201 (remaining time/album art - bottom),Radiotehnika МП-7201 (counter/album art - bottom) Sep 03 12:23:14 phts-np-01 volumio[11542]: info: PeppyMeterBasic ---800x480-test Sep 03 12:23:14 phts-np-01 volumio[11542]: info: PeppyMeterBasic ---800x480-balbuze Sep 03 12:23:14 phts-np-01 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 03 12:23:14 phts-np-01 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 03 12:23:14 phts-np-01 systemd[1]: Stopped go-librespot Daemon. Sep 03 12:23:14 phts-np-01 systemd[1]: Started go-librespot Daemon. Sep 03 12:23:14 phts-np-01 go-librespot[11917]: Librespot-go daemon starting... Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=info msg="generated new device id: 279436a5a440a9643fec225e37b2293ef1423d49" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="stored credentials found for y1cudeljhoqy5ghfq2ebg0cig" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="fetched new accesspoints: [ap2-gew4.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gew4.spotify.com:80 ap2-guc3.spotify.com:4070 ap2-gue1.spotify.com:443 ap2-gew1.spotify.com:80]" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="zeroconf server listening on port 37255" Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="obtained new client token: AAAAA9k3aQgeSscRD5rMq5I9HO1tBqmxxCCci3oKcxnvfMr9TKQQDJ0ZEi0rYjd35cprUCte9sMZNZLPIsc6rXY48TaC56WLgUM6mEGCOArV+yv2ox2IpKLwtIy2MOjYz6immlSYO/f+df22cmXDXFriDMMpdrhBxVf/kxN04oOBSf4FqtpK8Qomyrrz+3nWKFMNvfeaki6rzSNVW6aXrgDanKZ5e3TUsy1m9EwGXE1HEJyOIKx8yXhlZg==" Sep 03 12:23:14 phts-np-01 sudo[11659]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:14 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__MP3 at system boot, trial number 2 ,retrying in 5 seconds Sep 03 12:23:14 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:23:14 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:23:14 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:23:14 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:14+02:00" level=debug msg="completed keyexchange" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="completed challenge" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="authenticated as y1cudeljhoqy5ghfq2ebg0cig" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="authenticated as y1cudeljhoqy5ghfq2ebg0cig" Sep 03 12:23:15 phts-np-01 volumio[11542]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="dealer connection opened" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="initializing zeroconf session, username: y1cudeljhoqy5ghfq2ebg0cig" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="autoplay enabled: false" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="received connection id: MzlkNWRhNGUtODZiNi00MjRmLWE5ZmQtY2NjYjhkZDUwMTQ4K2RlYWxlcit0Y3A6Ly8wYWNhNThmZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQUU5MjdEODdGNDI4QzBEQUUwQ0ZGMzlBNTIwRDc3MDcxNDI3RjdBQkFFRkYxQjM2NUJDQUIzNEUzRkRDRDRFNA==" Sep 03 12:23:15 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:15+02:00" level=debug msg="put connect state because NEW_DEVICE" Sep 03 12:23:17 phts-np-01 volumio[11542]: info: Initializing connection to go-librespot Websocket Sep 03 12:23:17 phts-np-01 go-librespot[11917]: time="2024-09-03T12:23:17+02:00" level=debug msg="new websocket client" Sep 03 12:23:17 phts-np-01 volumio[11542]: info: Connection to go-librespot Websocket established Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Received Get System Version Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Received Get System Info Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Discovery: Getting this device information Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:19 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin bluetooth to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin multiroom to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin metavolumio to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin cd_controller to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 03 12:23:19 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 03 12:23:19 phts-np-01 sudo[11926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_MP3_ /mnt/NAS/HOME-NAS__MP3 Sep 03 12:23:19 phts-np-01 sudo[11926]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 03 12:23:20 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:20 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:20 phts-np-01 volumio[11542]: info: Starting MyVolumio Remote Streaming Endpoints Sep 03 12:23:20 phts-np-01 volumio[11542]: info: MyVolumio login type: Token Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 03 12:23:20 phts-np-01 volumio[11542]: info: Starting Streaming Service Transparent Proxy Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 03 12:23:20 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 03 12:23:20 phts-np-01 volumio[11542]: info: Streaming services startup Sep 03 12:23:20 phts-np-01 volumio[11542]: info: Starting Streaming Daemon Sep 03 12:23:20 phts-np-01 sudo[11938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 03 12:23:20 phts-np-01 sudo[11938]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:21 phts-np-01 sudo[11938]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:21 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 03 12:23:21 phts-np-01 volumio[11542]: info: Getting Spotify volume Sep 03 12:23:21 phts-np-01 sudo[11672]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:21 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:23:21 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:23:21 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Download Sep 03 12:23:21 phts-np-01 volumio[11542]: error: Cannot start Volumio Streaming Daemon Sep 03 12:23:21 phts-np-01 volumio[11542]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 03 12:23:21 phts-np-01 volumio[11542]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 03 12:23:21 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__FLAC at system boot, trial number 2 ,retrying in 5 seconds Sep 03 12:23:21 phts-np-01 volumio[11542]: info: Spotify volume: 100 Sep 03 12:23:21 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Sep 03 12:23:21 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:21 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:21 phts-np-01 volumio[11542]: error: MyVolumio Custom Token format not valid, refreshing it Sep 03 12:23:21 phts-np-01 volumio[11542]: STREAMING PROXY: Starting server on port 3245 Sep 03 12:23:21 phts-np-01 volumio[11542]: Node JS runtime: 14 Sep 03 12:23:22 phts-np-01 volumio[11542]: info: MyVolumio login type: Token Sep 03 12:23:22 phts-np-01 volumio[11542]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MyVolumio token set successfully Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MYVOLUMIO: Adding device Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MYVOLUMIO: Evaluating Server Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MyVolumio Plan changed: premium Sep 03 12:23:23 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Removing browser output: myVolumio user plan is not superstar Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Removing audio output: Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MYVOLUMIO: Adding device Sep 03 12:23:23 phts-np-01 volumio[11542]: info: MYVOLUMIO: Evaluating Server Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Remote config written successfully Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Starting Tunnel 1 Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Starting Tunnel Connection Checker Sep 03 12:23:23 phts-np-01 volumio[11542]: info: Completed starting MyVolumio Plugin Sep 03 12:23:24 phts-np-01 volumio[11542]: info: MYVolumio Device enabled Sep 03 12:23:24 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 03 12:23:24 phts-np-01 volumio[11542]: info: MyVolumio status changed Sep 03 12:23:24 phts-np-01 volumio[11542]: info: Streaming services startup Sep 03 12:23:24 phts-np-01 volumio[11542]: info: Starting Streaming Daemon Sep 03 12:23:24 phts-np-01 volumio[11542]: info: Setting Geolocation for MyVolumio to eu7 Sep 03 12:23:24 phts-np-01 sudo[11979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 03 12:23:24 phts-np-01 sudo[11979]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:24 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:24 phts-np-01 sudo[11979]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:24 phts-np-01 volumio[11542]: error: Cannot start Volumio Streaming Daemon Sep 03 12:23:24 phts-np-01 volumio[11542]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 03 12:23:24 phts-np-01 volumio[11542]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 03 12:23:24 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 03 12:23:24 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 03 12:23:24 phts-np-01 volumio[11542]: info: [1725359004256] Starting BluetoothController Sep 03 12:23:24 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 03 12:23:24 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 03 12:23:24 phts-np-01 sudo[11982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 03 12:23:24 phts-np-01 sudo[11982]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:24 phts-np-01 sudo[11982]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 volumio[11542]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: MultiRoom plugin initialized Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: STOPPING SNAPCLIENT Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: Snap server stop Sep 03 12:23:25 phts-np-01 sudo[11998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 03 12:23:25 phts-np-01 sudo[11998]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: STOPPING volumioStreaming Sep 03 12:23:25 phts-np-01 sudo[11998]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 sudo[12001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 03 12:23:25 phts-np-01 sudo[12001]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 sudo[12001]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 sudo[12004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 03 12:23:25 phts-np-01 sudo[12004]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 03 12:23:25 phts-np-01 sudo[12007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 03 12:23:25 phts-np-01 sudo[12007]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 sudo[12004]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 sudo[12007]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Preparing to generate the ALSA configuration file Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Updating MyVolumio device info Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 03 12:23:25 phts-np-01 volumio[11542]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Reading ALSA contributions from plugins. Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Setting Geolocation for MyVolumio to eu6 Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: Removed streaming files Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: volumioStreaming STOPPED Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: SNAPSERVER STOPPED Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: SNAPCLIENT STOPPED Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Asound.conf file unchanged, so no further update is needed Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Output device has changed, restarting MPD Sep 03 12:23:25 phts-np-01 sudo[12012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 03 12:23:25 phts-np-01 sudo[12012]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 sudo[12012]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 sudo[12015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 03 12:23:25 phts-np-01 sudo[12015]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 03 12:23:25 phts-np-01 systemd[1]: Stopping Music Player Daemon... Sep 03 12:23:25 phts-np-01 systemd[1]: mpd.service: Succeeded. Sep 03 12:23:25 phts-np-01 systemd[1]: Stopped Music Player Daemon. Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding METAVOLUMIO REST API Endpoints Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 03 12:23:25 phts-np-01 volumio[11542]: error: Hi Res Audio Failed Login: Missing Login Data Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 03 12:23:25 phts-np-01 volumio[11542]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 03 12:23:25 phts-np-01 sudo[12021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name PHTS NP-01 Sep 03 12:23:25 phts-np-01 sudo[12021]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 systemd[1]: Starting Music Player Daemon... Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Stopping AccessToken refresher cron Sep 03 12:23:25 phts-np-01 sudo[12021]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 sudo[12024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 03 12:23:25 phts-np-01 sudo[12024]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 volumio[11542]: info: AccessToken refresher cron started Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Stopping AccessToken refresher cron for QOBUZ Sep 03 12:23:25 phts-np-01 systemd[1]: Stopping Volumio Tidal Connect Service... Sep 03 12:23:25 phts-np-01 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 03 12:23:25 phts-np-01 systemd[1]: vtcs.service: Succeeded. Sep 03 12:23:25 phts-np-01 systemd[1]: Stopped Volumio Tidal Connect Service. Sep 03 12:23:25 phts-np-01 volumio[11542]: info: AccessToken refresher cron started for QOBUZ Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding TIDAL REST API Endpoints Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding QOBUZ REST API Endpoints Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 03 12:23:25 phts-np-01 sudo[12024]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Bluetooth name changed to PHTS NP-01 Sep 03 12:23:25 phts-np-01 volumio[11542]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 03 12:23:25 phts-np-01 volumio[11542]: info: MPD Permissions set Sep 03 12:23:25 phts-np-01 volumio[11542]: info: Successfully Added MyVolumio device Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 sudo[12020]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 03 12:23:25 phts-np-01 sudo[12020]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:25 phts-np-01 sudo[12020]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 03 12:23:25 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.12 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:129.0) Gecko/20100101 Firefox/129.0 Engine version: 3 Transport: polling Total Clients: 13 Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CALLMETHOD: system_controller system enableLiveLog true Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Launching a new LiveLog session Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Killing previous LiveLog session Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetQueue Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreStateMachine::getQueue Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CorePlayQueue::getQueue Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Live Log process terminated: null Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Successfully Added MyVolumio device Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Successfully Updated MyVolumio device Sep 03 12:23:26 phts-np-01 sudo[12036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=_WGX5ZHK-WV_9XH3,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.101/Music/_FLAC_ /mnt/NAS/HOME-NAS__FLAC Sep 03 12:23:26 phts-np-01 sudo[12036]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Updating MyVolumio device info Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:26 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 03 12:23:26 phts-np-01 systemd[1]: Started Music Player Daemon. Sep 03 12:23:26 phts-np-01 sudo[12015]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:26 phts-np-01 volumio[11542]: error: updateQueue error: null Sep 03 12:23:26 phts-np-01 volumio[11542]: info: Successfully Updated MyVolumio device Sep 03 12:23:27 phts-np-01 sudo[11678]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:27 phts-np-01 volumio[11542]: info: Cannot mount NAS HOME-NAS__Download at system boot, trial number 2 ,retrying in 5 seconds Sep 03 12:23:27 phts-np-01 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 03 12:23:27 phts-np-01 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Sep 03 12:23:27 phts-np-01 kernel: CIFS: Attempting to mount \\192.168.0.101\Music Sep 03 12:23:28 phts-np-01 sudo[12047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 03 12:23:28 phts-np-01 sudo[12047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:28 phts-np-01 autossh[4749]: received signal to exit (15) Sep 03 12:23:28 phts-np-01 systemd[1]: Stopping MyVolumio SSH Tunnel... Sep 03 12:23:28 phts-np-01 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Sep 03 12:23:28 phts-np-01 systemd[1]: sshtunnel.service: Succeeded. Sep 03 12:23:28 phts-np-01 systemd[1]: Stopped MyVolumio SSH Tunnel. Sep 03 12:23:28 phts-np-01 sudo[12050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 03 12:23:28 phts-np-01 sudo[12050]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:28 phts-np-01 systemd[1]: Started MyVolumio SSH Tunnel. Sep 03 12:23:28 phts-np-01 sudo[12047]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:28 phts-np-01 volumio[11542]: info: Remote SSH Started Sep 03 12:23:28 phts-np-01 autossh[12053]: port set to 0, monitoring disabled Sep 03 12:23:28 phts-np-01 autossh[12053]: starting ssh (count 1) Sep 03 12:23:28 phts-np-01 autossh[12053]: ssh child pid is 12056 Sep 03 12:23:28 phts-np-01 sudo[12050]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:28 phts-np-01 volumiossh-tunnel[12051]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Sep 03 12:23:28 phts-np-01 volumio[11542]: info: Volumio BT Module successfully started Sep 03 12:23:28 phts-np-01 volumio[11542]: info: TidalConnect service stoped! Sep 03 12:23:28 phts-np-01 volumio[11542]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 03 12:23:29 phts-np-01 volumio[11542]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioGetState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 sudo[12066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 03 12:23:29 phts-np-01 sudo[12066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 03 12:23:29 phts-np-01 systemd[1]: Started Volumio Tidal Connect Service. Sep 03 12:23:29 phts-np-01 sudo[12066]: pam_unix(sudo:session): session closed for user root Sep 03 12:23:29 phts-np-01 volumio[11542]: info: Executing endpoint tc_getconfig Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 03 12:23:29 phts-np-01 vtcs[12068]: STARTING TidalConnect services, version: 1.4.0.34 Sep 03 12:23:29 phts-np-01 vtcs[12068]: STARTED TidalConnect services. Sep 03 12:23:29 phts-np-01 volumio[11542]: info: Executing endpoint tc_connect Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 03 12:23:29 phts-np-01 volumio[11542]: info: Connecting to TidalConnect Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::servicePushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 03 12:23:29 phts-np-01 volumio[11542]: verbose: CURRENT POSITION 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::syncState stateService stop Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::syncState currentStatus stop Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: No code Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::servicePushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 03 12:23:29 phts-np-01 volumio[11542]: verbose: CURRENT POSITION 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::syncState stateService stop Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::syncState currentStatus stop Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: No code Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreStateMachine::pushState Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CorePlayQueue::getTrack 0 Sep 03 12:23:29 phts-np-01 volumio[11542]: info: CoreCommandRouter::volumioPushState Sep 03 12:23:29 phts-np-01 volumio[11542]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 12:23:29 phts-np-01 volumio[11542]: TypeError: Cannot read property 'get' of undefined Sep 03 12:23:29 phts-np-01 volumio[11542]: at Socket. (/data/plugins/user_interface/peppymeterbasic/index.js:179:26) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Socket.Emitter.emit (/data/plugins/user_interface/peppymeterbasic/node_modules/component-emitter/index.js:145:20) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Socket.onevent (/data/plugins/user_interface/peppymeterbasic/node_modules/socket.io-client/lib/socket.js:278:10) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Socket.onpacket (/data/plugins/user_interface/peppymeterbasic/node_modules/socket.io-client/lib/socket.js:236:12) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Manager. (/data/plugins/user_interface/peppymeterbasic/node_modules/component-bind/index.js:21:15) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Manager.Emitter.emit (/data/plugins/user_interface/peppymeterbasic/node_modules/component-emitter/index.js:145:20) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Manager.ondecoded (/data/plugins/user_interface/peppymeterbasic/node_modules/socket.io-client/lib/manager.js:349:8) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Decoder. (/data/plugins/user_interface/peppymeterbasic/node_modules/component-bind/index.js:21:15) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppymeterbasic/node_modules/component-emitter/index.js:145:20) Sep 03 12:23:29 phts-np-01 volumio[11542]: at Decoder.add (/data/plugins/user_interface/peppymeterbasic/node_modules/socket.io-parser/index.js:254:12) Sep 03 12:23:29 phts-np-01 volumio[11542]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 12:23:29 phts-np-01 sudo[12097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-03 12:22 Sep 03 12:23:29 phts-np-01 sudo[12097]: 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"