-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-05-08 13:52:22 CEST. -- May 08 13:51:00 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:00 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:00 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:00 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:02 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:02 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:05 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:05 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:07 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:07 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:08 noldstream go-librespot[22152]: time="2024-05-08T13:51:08+02:00" level=debug msg="fetched chunk 17/19, size: 524288" May 08 13:51:09 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:09 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:10 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:10 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:10 noldstream volumio[21923]: info: Listing playlists May 08 13:51:10 noldstream volumio[21923]: info: Listing playlists May 08 13:51:11 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:11 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:13 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:13 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:14 noldstream wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network May 08 13:51:15 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:15 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:17 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:17 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:19 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:19 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:20 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:20 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:21 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:21 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:21 noldstream go-librespot[22152]: time="2024-05-08T13:51:21+02:00" level=debug msg="fetched chunk 18/19, size: 524288" May 08 13:51:23 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:23 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:25 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:25 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:27 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:27 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:29 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:29 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:30 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:30 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:30 noldstream volumio[21923]: info: Listing playlists May 08 13:51:30 noldstream volumio[21923]: info: Listing playlists May 08 13:51:32 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:32 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:34 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:34 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:34 noldstream go-librespot[22152]: time="2024-05-08T13:51:34+02:00" level=debug msg="fetched chunk 19/19, size: 112540" May 08 13:51:36 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:36 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:38 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:38 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:40 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:40 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:40 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:40 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:42 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:42 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:44 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:44 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:46 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:46 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:48 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:48 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:50 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:50 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:50 noldstream volumio[21923]: info: CoreCommandRouter::volumioGetState May 08 13:51:50 noldstream volumio[21923]: info: CorePlayQueue::getTrack 26 May 08 13:51:50 noldstream volumio[21923]: info: Listing playlists May 08 13:51:50 noldstream volumio[21923]: info: Listing playlists May 08 13:51:51 noldstream volumio[21923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 08 13:51:51 noldstream volumio[21923]: info: Preload queue cleared May 08 13:51:52 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:52 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:54 noldstream volumio[21923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 08 13:51:54 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:54 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:54 noldstream volumio[21923]: info: Preload queue cleared May 08 13:51:57 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:57 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:57 noldstream volumio[21923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 08 13:51:57 noldstream volumio[21923]: info: Preload queue cleared May 08 13:51:59 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:51:59 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:51:59 noldstream volumio[21923]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 08 13:51:59 noldstream volumio[21923]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:51:59 noldstream volumio[21923]: TypeError: Cannot read property 'length' of undefined May 08 13:51:59 noldstream volumio[21923]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 08 13:51:59 noldstream volumio[21923]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 08 13:51:59 noldstream volumio[21923]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 08 13:51:59 noldstream volumio[21923]: at Parser.emit (events.js:315:20) May 08 13:51:59 noldstream volumio[21923]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 08 13:51:59 noldstream volumio[21923]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 08 13:51:59 noldstream volumio[21923]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 08 13:51:59 noldstream volumio[21923]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 08 13:51:59 noldstream volumio[21923]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 08 13:51:59 noldstream volumio[21923]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 08 13:51:59 noldstream volumio[21923]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 08 13:51:59 noldstream volumio[21923]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 08 13:51:59 noldstream volumio[21923]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 08 13:51:59 noldstream volumio[21923]: at IncomingMessage.emit (events.js:327:22) May 08 13:51:59 noldstream volumio[21923]: at endReadableNT (internal/streams/readable.js:1327:12) May 08 13:51:59 noldstream volumio[21923]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 08 13:51:59 noldstream volumio[21923]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:51:59 noldstream wpa_supplicant[810]: RRM: Ignoring radio measurement request: Not RRM network May 08 13:51:59 noldstream sudo[5073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 13:50 May 08 13:51:59 noldstream sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:51:59 noldstream sudo[5073]: pam_unix(sudo:session): session closed for user root May 08 13:51:59 noldstream volumio-remote-updater[582]: [2024-05-08 13:51:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 08 13:51:59 noldstream volumio-remote-updater[582]: [2024-05-08 13:51:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 08 13:51:59 noldstream go-librespot[22152]: time="2024-05-08T13:51:59+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 08 13:51:59 noldstream systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 08 13:51:59 noldstream systemd[1]: volumio.service: Failed with result 'exit-code'. May 08 13:51:59 noldstream systemd[1]: Started dynamicswap service. May 08 13:51:59 noldstream systemd[1]: dynamicswap.service: Succeeded. May 08 13:51:59 noldstream systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 08 13:51:59 noldstream systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. May 08 13:51:59 noldstream systemd[1]: Stopped Volumio Backend Module. May 08 13:51:59 noldstream systemd[1]: Started Volumio Backend Module. May 08 13:51:59 noldstream systemd[1]: Started dynamicswap service. May 08 13:51:59 noldstream systemd[1]: dynamicswap.service: Succeeded. May 08 13:52:01 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:01 noldstream volumio[5084]: info: ----- Volumio3 ---- May 08 13:52:01 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:01 noldstream volumio[5084]: info: ----- System startup ---- May 08 13:52:01 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:01 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:01 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:01 noldstream volumio[5084]: info: MYVOLUMIO Environment detected May 08 13:52:01 noldstream volumio[5084]: info: Plugin folders cleanup May 08 13:52:01 noldstream volumio[5084]: info: Scanning into folder /volumio/app/plugins/ May 08 13:52:01 noldstream volumio[5084]: info: Scanning category audio_interface May 08 13:52:01 noldstream volumio[5084]: info: Scanning category miscellanea May 08 13:52:01 noldstream volumio[5084]: info: Scanning category music_service May 08 13:52:01 noldstream volumio[5084]: info: Scanning category plugins.json May 08 13:52:01 noldstream volumio[5084]: info: Scanning category system_controller May 08 13:52:01 noldstream volumio[5084]: info: Scanning category user_interface May 08 13:52:01 noldstream volumio[5084]: info: Scanning into folder /data/plugins/ May 08 13:52:01 noldstream volumio[5084]: info: Scanning category music_service May 08 13:52:01 noldstream volumio[5084]: info: Scanning category system_controller May 08 13:52:01 noldstream volumio[5084]: info: Scanning category user_interface May 08 13:52:01 noldstream volumio[5084]: info: Plugin folders cleanup completed May 08 13:52:01 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:01 noldstream volumio[5084]: info: ----- Core plugins startup ---- May 08 13:52:01 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:01 noldstream volumio[5084]: info: Loading plugins from folder /volumio/app/plugins/ May 08 13:52:01 noldstream volumio[5084]: info: Adding plugin upnp to MyMusic Plugins May 08 13:52:01 noldstream volumio[5084]: info: Adding plugin airplay_emulation to MyMusic Plugins May 08 13:52:01 noldstream volumio[5084]: info: Adding plugin upnp_browser to MyMusic Plugins May 08 13:52:01 noldstream volumio[5084]: info: Loading plugins from folder /data/plugins/ May 08 13:52:01 noldstream volumio[5084]: info: Loading plugin "system"... May 08 13:52:01 noldstream volumio[5084]: info: Loading plugin "appearance"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "network"... May 08 13:52:02 noldstream volumio[5084]: info: Refreshing Cached IP Addresses May 08 13:52:02 noldstream sudo[5134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 13:52:02 noldstream sudo[5134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:02 noldstream sudo[5134]: pam_unix(sudo:session): session closed for user root May 08 13:52:02 noldstream sudo[5136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 13:52:02 noldstream sudo[5136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "services"... May 08 13:52:02 noldstream sudo[5136]: pam_unix(sudo:session): session closed for user root May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "alsa_controller"... May 08 13:52:02 noldstream sudo[5140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 08 13:52:02 noldstream sudo[5140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:02 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "wizard"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "networkfs"... May 08 13:52:02 noldstream volumio[5084]: info: Cannot mount NAS noldNAS at system boot, trial number 1 ,retrying in 5 seconds May 08 13:52:02 noldstream volumio[5084]: info: Cannot mount NAS noldOVM at system boot, trial number 1 ,retrying in 5 seconds May 08 13:52:02 noldstream volumio[5084]: info: Starting Udev Watcher for removable devices May 08 13:52:02 noldstream volumio[5084]: info: Ignoring mount for partition: boot May 08 13:52:02 noldstream volumio[5084]: info: Ignoring mount for partition: volumio May 08 13:52:02 noldstream volumio[5084]: info: Ignoring mount for partition: volumio_data May 08 13:52:02 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "volumio_command_line_client"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "upnp"... May 08 13:52:02 noldstream volumio[5084]: info: [1715169122335] Starting Upmpd Daemon May 08 13:52:02 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "my_music"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "mpd"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "upnp_browser"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "alarm-clock"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "airplay_emulation"... May 08 13:52:02 noldstream volumio[5084]: info: Starting Shairport Sync May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "last_100"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "webradio"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "i2s_dacs"... May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "volumiodiscovery"... May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 13:52:02 noldstream node[5084]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 08 13:52:02 noldstream node[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** For more information see May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 13:52:02 noldstream volumio[5084]: *** WARNING *** For more information see May 08 13:52:02 noldstream node[5084]: *** WARNING *** For more information see May 08 13:52:02 noldstream node[5084]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 08 13:52:02 noldstream node[5084]: *** WARNING *** Please fix your application to use the native API of Avahi! May 08 13:52:02 noldstream node[5084]: *** WARNING *** For more information see May 08 13:52:02 noldstream volumio[5084]: info: Applying required configuration parameters for plugin volumiodiscovery May 08 13:52:02 noldstream volumio[5084]: info: Discovery: Started advertising with name: noldStream May 08 13:52:02 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 08 13:52:02 noldstream volumio[5084]: info: Loading plugin "spop"... May 08 13:52:03 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:03 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "autostart"... May 08 13:52:03 noldstream volumio[5084]: info: Applying required configuration parameters for plugin autostart May 08 13:52:03 noldstream volumio[5084]: info: AutoStart - onVolumioStart - read config.json May 08 13:52:03 noldstream volumio[5084]: info: Plugin now_playing is not enabled May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "outputs"... May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "albumart"... May 08 13:52:03 noldstream volumio[5084]: info: Plugin example_plugin is not enabled May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "inputs"... May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "updater_comm"... May 08 13:52:03 noldstream volumio[5084]: info: Plugin mpdemulation is not enabled May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "rest_api"... May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "websocket"... May 08 13:52:03 noldstream volumio[5084]: info: Loading plugin "backup_restore"... May 08 13:52:04 noldstream volumio[5084]: Forking 3 albumart workers May 08 13:52:04 noldstream volumio[5084]: info: Applying required configuration parameters for plugin backup_restore May 08 13:52:04 noldstream volumio[5084]: info: Loading plugin "Systeminfo"... May 08 13:52:04 noldstream volumio[5084]: info: Loading plugin "touch_display"... May 08 13:52:04 noldstream volumio-remote-updater[582]: [2024-05-08 13:52:04] [connect] Successful connection May 08 13:52:04 noldstream volumio[5084]: Starting albumart workers May 08 13:52:04 noldstream volumio[5084]: Starting albumart workers May 08 13:52:04 noldstream volumio[5084]: info: Applying required configuration parameters for plugin touch_display May 08 13:52:04 noldstream volumio[5084]: info: Loading i18n strings for locale de May 08 13:52:04 noldstream volumio[5084]: Starting albumart workers May 08 13:52:04 noldstream volumio[5084]: Updating browse sources language May 08 13:52:04 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::initPlayerControls May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:05 noldstream volumio[5084]: Express server listening on port 3000 May 08 13:52:05 noldstream volumio[5084]: [Metrics] WebUI: 4s 410.65ms May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::resetVolumioState May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::getcurrentVolume May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioRetrievevolume May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::pushState May 08 13:52:05 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioPushState May 08 13:52:05 noldstream volumio[5084]: verbose: New Socket.io Connection to 192.168.2.20 from 192.168.2.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 1 May 08 13:52:05 noldstream volumio[5084]: verbose: New Socket.io Connection to 127.0.0.1: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 Total Clients: 2 May 08 13:52:05 noldstream volumio[5084]: verbose: New Socket.io Connection to 192.168.2.20 from 192.168.2.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 May 08 13:52:05 noldstream volumio-remote-updater[582]: [2024-05-08 13:52:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715169124 101 May 08 13:52:05 noldstream volumio[5084]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 May 08 13:52:05 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:05 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:05 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:05 noldstream volumio[5084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: Reloading queue from file May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::setRepeat false single undefined May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::pushState May 08 13:52:05 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioPushState May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::setRandom false May 08 13:52:05 noldstream volumio[5084]: info: CoreStateMachine::pushState May 08 13:52:05 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioPushState May 08 13:52:05 noldstream volumio[5084]: info: Setting Device type: Raspberry PI May 08 13:52:05 noldstream volumio[5084]: info: Completed loading Core Plugins May 08 13:52:05 noldstream volumio[5084]: info: Preparing to generate the ALSA configuration file May 08 13:52:05 noldstream volumio[5084]: info: Asound.conf file unchanged, so no further update is needed May 08 13:52:05 noldstream volumio[5084]: info: Output device has changed, restarting MPD May 08 13:52:05 noldstream sudo[5217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 13:52:05 noldstream sudo[5217]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:05 noldstream sudo[5217]: pam_unix(sudo:session): session closed for user root May 08 13:52:05 noldstream volumio[5084]: info: Output device has changed, restarting Shairport Sync May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:05 noldstream sudo[5221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 13:52:05 noldstream sudo[5221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:05 noldstream systemd[1]: Stopping Music Player Daemon... May 08 13:52:05 noldstream volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 13:52:05 noldstream volumio[5084]: info: ___________ START PLUGINS ___________ May 08 13:52:05 noldstream systemd[1]: mpd.service: Succeeded. May 08 13:52:05 noldstream systemd[1]: Stopped Music Player Daemon. May 08 13:52:05 noldstream volumio[5084]: info: ControllerMpd::onStart: Initializing MPD May 08 13:52:05 noldstream volumio[5084]: info: Creating MPD Configuration file May 08 13:52:05 noldstream sudo[5228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:05 noldstream systemd[1]: Starting Music Player Daemon... May 08 13:52:05 noldstream sudo[5228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 13:52:05 noldstream volumio[5084]: info: [1715169125660] CoreMusicLibrary::Adding element Medienserver May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:05 noldstream sudo[5228]: pam_unix(sudo:session): session closed for user root May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:05 noldstream sudo[5232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 08 13:52:05 noldstream sudo[5232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:05 noldstream systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 08 13:52:05 noldstream systemd[1]: mpd.service: Succeeded. May 08 13:52:05 noldstream systemd[1]: Stopped Music Player Daemon. May 08 13:52:05 noldstream systemd[1]: Starting Music Player Daemon... May 08 13:52:05 noldstream volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 13:52:05 noldstream volumio[5084]: info: [1715169125771] CoreMusicLibrary::Adding element Last_100 May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 13:52:05 noldstream volumio[5084]: info: [1715169125773] CoreMusicLibrary::Adding element Webradio May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 13:52:05 noldstream volumio[5084]: info: Creating Spotify config file May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:05 noldstream volumio[5084]: info: AutoStart - onStart May 08 13:52:05 noldstream volumio[5084]: info: Loading i18n strings for locale de May 08 13:52:05 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 13:52:05 noldstream volumio[5084]: info: Volumio Calling Home May 08 13:52:05 noldstream sudo[5140]: pam_unix(sudo:session): session closed for user root May 08 13:52:05 noldstream sudo[5245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 08 13:52:05 noldstream sudo[5245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream sudo[5250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 08 13:52:06 noldstream sudo[5250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream sudo[5249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 08 13:52:06 noldstream sudo[5249]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream volumio[5084]: info: Discovery: adding 8b0eb05b-cf1d-440f-9027-7af6a42d4779 May 08 13:52:06 noldstream volumio[5084]: info: Discovery: Found device noldStream May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:06 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:06 noldstream sudo[5245]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream systemd[1]: Reloading. May 08 13:52:06 noldstream volumio[5084]: info: touch_display: Backlight interface detected. May 08 13:52:06 noldstream volumio[5084]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 08 13:52:06 noldstream volumio[5084]: info: MPD Permissions set May 08 13:52:06 noldstream volumio[5084]: info: MPD Permissions set May 08 13:52:06 noldstream volumio[5084]: info: Volumio called home May 08 13:52:06 noldstream volumio[5084]: info: Spotify config file written May 08 13:52:06 noldstream sudo[5258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 08 13:52:06 noldstream sudo[5258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream volumio[5084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 13:52:06 noldstream 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. May 08 13:52:06 noldstream volumio[5084]: info: touch_display: Raspberry Pi Foundation touch screen detected. May 08 13:52:06 noldstream volumio[5084]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 08 13:52:06 noldstream volumio[5084]: SPOTIFY: BQA2jZfG7mWT7qDfbrIlrutWwJFEnzqCf4BtySpYJcxVKekM5rRhrmkXEk1Pq1QlC87FFv_0S3DkCZ_e3g0MnRir4jZgUWHLTQWOW3NvfAOYLLPt0eOX9B8xCyt4CD4djVPM0gfnEIChV53XgL0JgWWYRdE9xw56qDQvedWGEF1JgNsmBxhmJIl8Dwl1YGLiKYdnlhG1HpsQWIs-FYVlq7-VXcCQ8ZPIJDCGJkQHgJ9vJw May 08 13:52:06 noldstream volumio[5084]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 08 13:52:06 noldstream volumio[5084]: info: New Spotify access token = BQA2jZfG7mWT7qDfbrIlrutWwJFEnzqCf4BtySpYJcxVKekM5rRhrmkXEk1Pq1QlC87FFv_0S3DkCZ_e3g0MnRir4jZgUWHLTQWOW3NvfAOYLLPt0eOX9B8xCyt4CD4djVPM0gfnEIChV53XgL0JgWWYRdE9xw56qDQvedWGEF1JgNsmBxhmJIl8Dwl1YGLiKYdnlhG1HpsQWIs-FYVlq7-VXcCQ8ZPIJDCGJkQHgJ9vJw May 08 13:52:06 noldstream volumio[5084]: info: Spotify credentials grant success - running version from March 24, 2019 May 08 13:52:06 noldstream sudo[5282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 08 13:52:06 noldstream mpd[5237]: May 08 13:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 08 13:52:06 noldstream sudo[5282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream sudo[5282]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream volumio[5084]: verbose: New Socket.io Connection to 127.0.0.1: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 Total Clients: 7 May 08 13:52:06 noldstream volumio[5084]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 08 13:52:06 noldstream volumio[5084]: info: Starting Shairport Sync May 08 13:52:06 noldstream sudo[5285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness May 08 13:52:06 noldstream sudo[5285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream sudo[5285]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream volumio[5084]: info: Starting Shairport Sync May 08 13:52:06 noldstream sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 13:52:06 noldstream sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream volumio[5084]: info: Starting Shairport Sync May 08 13:52:06 noldstream volumio[5084]: info: touch_display: File permissions for backlight brightness control set. May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 13:52:06 noldstream sudo[5293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 13:52:06 noldstream sudo[5293]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream sudo[5295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 08 13:52:06 noldstream sudo[5295]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetVisibleSources May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:06 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 13:52:06 noldstream volumio[5084]: info: Received Get System Info May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 13:52:06 noldstream volumio[5084]: info: Discovery: Getting this device information May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:06 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:06 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:06 noldstream volumio[5084]: info: Listing playlists May 08 13:52:06 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:06 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:06 noldstream systemd[1]: Started Music Player Daemon. May 08 13:52:06 noldstream sudo[5221]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream sudo[5232]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream sudo[5250]: pam_unix(sudo:session): session closed for user root May 08 13:52:06 noldstream systemd[1]: Reloading. May 08 13:52:07 noldstream volumio[5084]: info: touch_display: systemctl daemon-reload succeeded. May 08 13:52:07 noldstream sudo[5318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 08 13:52:07 noldstream sudo[5318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:07 noldstream volumio[5084]: error: MPD error: The expression evaluated to a falsy value: May 08 13:52:07 noldstream volumio[5084]: assert.ok(self.idling) May 08 13:52:07 noldstream volumio[5084]: error: The expression evaluated to a falsy value: May 08 13:52:07 noldstream volumio[5084]: assert.ok(self.idling) May 08 13:52:07 noldstream volumio[5084]: info: MPD running with PID5237 May 08 13:52:07 noldstream volumio[5084]: ,establishing connection May 08 13:52:07 noldstream volumio[5084]: error: MPD error: The expression evaluated to a falsy value: May 08 13:52:07 noldstream volumio[5084]: assert.ok(self.idling) May 08 13:52:07 noldstream volumio[5084]: error: The expression evaluated to a falsy value: May 08 13:52:07 noldstream volumio[5084]: assert.ok(self.idling) May 08 13:52:07 noldstream volumio[5084]: SPOTIFY: User informations: {"display_name":"nold68","external_urls":{"spotify":"https://open.spotify.com/user/nold68"},"href":"https://api.spotify.com/v1/users/nold68","id":"nold68","images":[],"type":"user","uri":"spotify:user:nold68","followers":{"href":null,"total":0},"country":"DE","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"thomas.nett@gmx.de"} May 08 13:52:07 noldstream volumio[5084]: info: Spotify Successfully logged in May 08 13:52:07 noldstream volumio[5084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 08 13:52:07 noldstream volumio[5084]: info: [1715169127223] CoreMusicLibrary::Adding element Spotify May 08 13:52:07 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:07 noldstream volumio[5084]: Cannot find translation for source Spotify May 08 13:52:07 noldstream volumio[5084]: error: updateQueue error: null May 08 13:52:07 noldstream volumio[5084]: info: Cannot mount NAS noldNAS at system boot, trial number 2 ,retrying in 5 seconds May 08 13:52:07 noldstream volumio[5084]: info: Cannot mount NAS noldOVM at system boot, trial number 2 ,retrying in 5 seconds May 08 13:52:07 noldstream 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. May 08 13:52:07 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:07 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:07 noldstream sudo[5249]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream volumio[5084]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 08 13:52:07 noldstream sudo[5318]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 08 13:52:07 noldstream systemd[1]: Stopping go-librespot Daemon... May 08 13:52:07 noldstream volumio[5084]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 08 13:52:07 noldstream volumio[5084]: info: touch_display: Volumio Kiosk started. May 08 13:52:07 noldstream systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 08 13:52:07 noldstream systemd[1]: go-librespot-daemon.service: Succeeded. May 08 13:52:07 noldstream systemd[1]: Stopped go-librespot Daemon. May 08 13:52:07 noldstream systemd[1]: shairport-sync.service: Succeeded. May 08 13:52:07 noldstream systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 08 13:52:07 noldstream systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 08 13:52:07 noldstream sudo[5288]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream systemd[1]: Started go-librespot Daemon. May 08 13:52:07 noldstream sudo[5295]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream go-librespot[5324]: Librespot-go daemon starting... May 08 13:52:07 noldstream sudo[5293]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream sudo[5258]: pam_unix(sudo:session): session closed for user root May 08 13:52:07 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:07 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:07 noldstream volumio[5084]: info: Completed starting Core Plugins May 08 13:52:07 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:07 noldstream volumio[5084]: info: ----- MyVolumio plugins startup ---- May 08 13:52:07 noldstream volumio[5084]: info: ------------------------------------------- May 08 13:52:07 noldstream volumio[5084]: info: [MyVolumio PluginManager] Fetching plans data.... May 08 13:52:07 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:07 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:07 noldstream go-librespot[5324]: time="2024-05-08T13:52:07+02:00" level=info msg="generated new device id: 3c1642765c78a79cde8b440c69f2e9a25b397bc7" May 08 13:52:07 noldstream go-librespot[5324]: time="2024-05-08T13:52:07+02:00" level=debug msg="stored credentials found for nold68" May 08 13:52:07 noldstream volumio[5084]: info: Shairport-Sync Started May 08 13:52:07 noldstream volumio[5084]: Error adding Membership: Error: addMembership EINVAL May 08 13:52:07 noldstream volumio[5084]: info: Shairport-Sync Started May 08 13:52:07 noldstream volumio[5084]: info: Shairport-Sync Started May 08 13:52:07 noldstream volumio[5084]: info: touch_display: X display number found: 0 May 08 13:52:07 noldstream volumio[5084]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 08 13:52:07 noldstream volumio[5084]: info: touch_display: X display number found: 0 May 08 13:52:07 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:07 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: touch_display: Setting screensaver timeout to 120 seconds. May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="obtained new client token: AABRLXtjPka85SFlNrY7tCHcAM4GjQlcx7pmkkk1lnOh2tlOSQQoMahDNEfKP5TP90ngtyqwgmdkqg6t8cKN4TF6dB2AoP49K/0Ki4y+8edJr2RK2crK04+yeZpcUNaYNrcgALnIDCXvwtkNO6tvupNZkLSQJxzAHWN3nMPY0HLSa90QdFkN78Oo3Syo1kgPJwxDTw5YO65X521hhByjs6C+/ueovmA0hQXguwNs3qtPI5prIMwpsiUVH7YWgA==" May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" May 08 13:52:08 noldstream volumio[5084]: verbose: New Socket.io Connection to 192.168.2.20 from 192.168.2.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="completed keyexchange" May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetVisibleSources May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 08 13:52:08 noldstream volumio[5084]: info: Received Get System Info May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 13:52:08 noldstream volumio[5084]: info: Discovery: Getting this device information May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:08 noldstream volumio[5084]: info: Listing playlists May 08 13:52:08 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetQueue May 08 13:52:08 noldstream volumio[5084]: info: CoreStateMachine::getQueue May 08 13:52:08 noldstream volumio[5084]: info: CorePlayQueue::getQueue May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="completed challenge" May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="authenticated as nold68" May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="authenticated as nold68" May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 08 13:52:08 noldstream go-librespot[5324]: time="2024-05-08T13:52:08+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]" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="dealer connection opened" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="received connection id: ZWY0YjIxNDgtMzY3OC00YWE0LTk4MDItNTdkYjk4YmE2MmM0K2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXozanMuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzVGQzg5MzBFMkY3QTg0MzUxREY3OTE0RkYxRUU2NjU5N0ExQjVCREE1OUU5QTE4Mzc1RTdFRkU1RkM0NUQwMjk=" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1541" May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 08 13:52:09 noldstream go-librespot[5324]: time="2024-05-08T13:52:09+02:00" level=debug msg="put connect state because NEW_DEVICE" May 08 13:52:09 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:09 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 08 13:52:10 noldstream volumio[5084]: info: Received Get System Info May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 08 13:52:10 noldstream volumio[5084]: info: Discovery: Getting this device information May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:10 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 08 13:52:10 noldstream volumio[5084]: info: go-librespot daemon successfully initialized May 08 13:52:10 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:10 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:10 noldstream volumio[5084]: info: Listing playlists May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetBrowseSources May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetBrowseSources May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetBrowseSources May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 13:52:11 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:11 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:11 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 13:52:11 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:11 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:12 noldstream volumio[5084]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 08 13:52:12 noldstream volumio[5084]: info: Cannot mount NAS noldNAS at system boot, trial number 3 ,retrying in 5 seconds May 08 13:52:12 noldstream volumio[5084]: info: Cannot mount NAS noldOVM at system boot, trial number 3 ,retrying in 5 seconds May 08 13:52:12 noldstream sudo[5375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 13:52:12 noldstream sudo[5375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:12 noldstream sudo[5375]: pam_unix(sudo:session): session closed for user root May 08 13:52:12 noldstream sudo[5378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 13:52:12 noldstream sudo[5378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:12 noldstream sudo[5378]: pam_unix(sudo:session): session closed for user root May 08 13:52:12 noldstream sudo[5381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 08 13:52:12 noldstream sudo[5381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:12 noldstream sudo[5381]: pam_unix(sudo:session): session closed for user root May 08 13:52:13 noldstream volumio[5084]: info: Upmpdcli Daemon Started May 08 13:52:13 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:13 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:13 noldstream volumio[5084]: info: Initializing connection to go-librespot Websocket May 08 13:52:13 noldstream go-librespot[5324]: time="2024-05-08T13:52:13+02:00" level=debug msg="new websocket client" May 08 13:52:13 noldstream volumio[5084]: info: Connection to go-librespot Websocket established May 08 13:52:15 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 13:52:15 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:15 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin bluetooth to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin multiroom to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin metavolumio to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin cd_controller to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin smart_inputs to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: Adding plugin tidalconnect to MyMusic Plugins May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 08 13:52:16 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:16 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:16 noldstream volumio[5084]: info: Starting MyVolumio Remote Streaming Endpoints May 08 13:52:16 noldstream volumio[5084]: info: MyVolumio login type: Token May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 08 13:52:16 noldstream volumio[5084]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 08 13:52:17 noldstream volumio[5084]: info: Starting Streaming Service Transparent Proxy May 08 13:52:17 noldstream volumio[5084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 08 13:52:17 noldstream volumio[5084]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 08 13:52:17 noldstream volumio[5084]: info: Streaming services startup May 08 13:52:17 noldstream volumio[5084]: info: Starting Streaming Daemon May 08 13:52:17 noldstream sudo[5407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 13:52:17 noldstream sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:17 noldstream sudo[5407]: pam_unix(sudo:session): session closed for user root May 08 13:52:17 noldstream volumio[5084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 08 13:52:17 noldstream volumio[5084]: info: Getting Spotify volume May 08 13:52:17 noldstream volumio[5084]: error: Cannot start Volumio Streaming Daemon May 08 13:52:17 noldstream volumio[5084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 13:52:17 noldstream volumio[5084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 13:52:17 noldstream volumio[5084]: info: Cannot mount NAS noldNAS at system boot, trial number 4 ,retrying in 5 seconds May 08 13:52:17 noldstream volumio[5084]: info: Cannot mount NAS noldOVM at system boot, trial number 4 ,retrying in 5 seconds May 08 13:52:17 noldstream volumio[5084]: info: Spotify volume: 100 May 08 13:52:17 noldstream volumio[5084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 08 13:52:17 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:17 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:17 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:17 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:17 noldstream volumio[5084]: STREAMING PROXY: Starting server on port 3245 May 08 13:52:17 noldstream volumio[5084]: Node JS runtime: 14 May 08 13:52:18 noldstream volumio[5084]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 08 13:52:18 noldstream volumio[5084]: info: MyVolumio token set successfully May 08 13:52:18 noldstream volumio[5084]: info: MYVOLUMIO: Adding device May 08 13:52:18 noldstream volumio[5084]: info: MYVOLUMIO: Evaluating Server May 08 13:52:19 noldstream volumio[5084]: info: MyVolumio status changed May 08 13:52:19 noldstream volumio[5084]: info: Streaming services startup May 08 13:52:19 noldstream volumio[5084]: info: Starting Streaming Daemon May 08 13:52:19 noldstream volumio[5084]: info: Removing browser output: myVolumio user plan is not superstar May 08 13:52:19 noldstream volumio[5084]: info: Removing audio output: May 08 13:52:19 noldstream volumio[5084]: info: Stoppping Tunnel 1 May 08 13:52:19 noldstream sudo[5440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 13:52:19 noldstream sudo[5440]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:19 noldstream sudo[5440]: pam_unix(sudo:session): session closed for user root May 08 13:52:19 noldstream volumio[5084]: error: Cannot start Volumio Streaming Daemon May 08 13:52:19 noldstream volumio[5084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 13:52:19 noldstream volumio[5084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 13:52:19 noldstream sudo[5443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 08 13:52:19 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 08 13:52:19 noldstream sudo[5443]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:19 noldstream sudo[5443]: pam_unix(sudo:session): session closed for user root May 08 13:52:19 noldstream volumio[5084]: info: Remote SSH Stopped May 08 13:52:19 noldstream sudo[5446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/noldOVM May 08 13:52:19 noldstream sudo[5446]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 13:52:19 noldstream systemd[1]: mnt-NAS-noldOVM.mount: Succeeded. May 08 13:52:19 noldstream kernel: CIFS: VFS: \\192.168.2.19\Public BAD_NETWORK_NAME: \\192.168.2.19\Public May 08 13:52:19 noldstream kernel: CIFS: VFS: reconnect tcon failed rc = -2 May 08 13:52:19 noldstream sudo[5446]: pam_unix(sudo:session): session closed for user root May 08 13:52:19 noldstream volumio[5084]: info: Share noldOVM successfully unmounted May 08 13:52:19 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 13:52:19 noldstream volumio[5084]: info: Setting Geolocation for MyVolumio to eu4 May 08 13:52:19 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:19 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:19 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:20 noldstream volumio[5084]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 08 13:52:20 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 13:52:20 noldstream volumio[5084]: info: Updating MyVolumio device info May 08 13:52:20 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:20 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:20 noldstream volumio[5084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 13:52:20 noldstream volumio[5084]: info: CoreCommandRouter::volumioGetState May 08 13:52:20 noldstream volumio[5084]: info: CorePlayQueue::getTrack 0 May 08 13:52:21 noldstream volumio[5084]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 08 13:52:22 noldstream volumio[5084]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 08 13:52:22 noldstream volumio[5084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:52:22 noldstream volumio[5084]: TypeError: Cannot read property 'replace' of undefined May 08 13:52:22 noldstream volumio[5084]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 08 13:52:22 noldstream volumio[5084]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 08 13:52:22 noldstream volumio[5084]: at listOnTimeout (internal/timers.js:554:17) May 08 13:52:22 noldstream volumio[5084]: at processTimers (internal/timers.js:497:7) May 08 13:52:22 noldstream volumio[5084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 13:52:22 noldstream sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 13:51 May 08 13:52:22 noldstream sudo[5482]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"