-- Logs begin at Mon 2024-04-22 19:32:52 UTC, end at Mon 2024-04-22 20:35:32 UTC. --
Apr 22 20:34:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 22 20:34:03 volumio volumio-remote-updater[547]: [2024-04-22 20:34:03] [info] asio async_read_at_least error: system:104 (Connection reset by peer)
Apr 22 20:34:03 volumio volumio-remote-updater[547]: [2024-04-22 20:34:03] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error)
Apr 22 20:34:03 volumio volumio-remote-updater[547]: [2024-04-22 20:34:03] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006]
Apr 22 20:34:03 volumio ntfs-3g[1156]: Unmounting /dev/sdb1 (Музыка 500г 2)
Apr 22 20:34:03 volumio systemd[1]: media-\xd0\x9c\xd1\x83\xd0\xb7\xd1\x8b\xd0\xba\xd0\xb0_500\xd0\xb3_2.mount: Succeeded.
Apr 22 20:34:03 volumio systemd[1]: media-\xd0\x9c\xd1\x83\xd0\xb7\xd1\x8b\xd0\xba\xd0\xb0_500\xd0\xb3.mount: Succeeded.
Apr 22 20:34:03 volumio ntfs-3g[1145]: Unmounting /dev/sda1 (Музыка 500г)
Apr 22 20:34:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 22 20:34:03 volumio systemd[1]: Started dynamicswap service.
Apr 22 20:34:03 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 22 20:34:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 22 20:34:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Apr 22 20:34:03 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 22 20:34:03 volumio systemd[1]: Started Volumio Backend Module.
Apr 22 20:34:03 volumio systemd[1]: Started dynamicswap service.
Apr 22 20:34:04 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 22 20:34:04 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:04 volumio volumio[12117]: info: ----- Volumio3 ----
Apr 22 20:34:04 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:04 volumio volumio[12117]: info: ----- System startup ----
Apr 22 20:34:04 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:04 volumio volumio[12117]: info: MYVOLUMIO Environment detected
Apr 22 20:34:04 volumio volumio[12117]: info: Plugin folders cleanup
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning into folder /volumio/app/plugins/
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category audio_interface
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category miscellanea
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category music_service
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category plugins.json
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category system_controller
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning category user_interface
Apr 22 20:34:04 volumio volumio[12117]: info: Scanning into folder /data/plugins/
Apr 22 20:34:04 volumio volumio[12117]: info: Plugin folders cleanup completed
Apr 22 20:34:04 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:04 volumio volumio[12117]: info: ----- Core plugins startup ----
Apr 22 20:34:04 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:04 volumio volumio[12117]: info: Loading plugins from folder /volumio/app/plugins/
Apr 22 20:34:04 volumio volumio[12117]: info: Adding plugin upnp to MyMusic Plugins
Apr 22 20:34:04 volumio volumio[12117]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 22 20:34:04 volumio volumio[12117]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 22 20:34:04 volumio volumio[12117]: info: Loading plugins from folder /data/plugins/
Apr 22 20:34:04 volumio volumio[12117]: info: Loading plugin "system"...
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "appearance"...
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "network"...
Apr 22 20:34:05 volumio volumio[12117]: info: Refreshing Cached IP Addresses
Apr 22 20:34:05 volumio sudo[12145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 22 20:34:05 volumio sudo[12145]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:05 volumio sudo[12145]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:05 volumio sudo[12147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 22 20:34:05 volumio sudo[12147]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "services"...
Apr 22 20:34:05 volumio sudo[12147]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "alsa_controller"...
Apr 22 20:34:05 volumio sudo[12150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 22 20:34:05 volumio sudo[12150]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:05 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "wizard"...
Apr 22 20:34:05 volumio volumio[12117]: info: Loading plugin "networkfs"...
Apr 22 20:34:05 volumio volumio[12117]: info: Starting Udev Watcher for removable devices
Apr 22 20:34:05 volumio sudo[12156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:34:05 volumio sudo[12156]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:05 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:34:05 volumio volumio[12117]: info: Mounting Device Музыка_500г
Apr 22 20:34:05 volumio sudo[12164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Музыка_500г -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:34:05 volumio sudo[12164]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:06 volumio sudo[12164]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:06 volumio ntfs-3g[12168]: Version 2017.3.23AR.3 integrated FUSE 28
Apr 22 20:34:06 volumio ntfs-3g[12168]: Mounted /dev/sda1 (Read-Write, label "Музыка 500г", NTFS 3.1)
Apr 22 20:34:06 volumio ntfs-3g[12168]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:34:06 volumio ntfs-3g[12168]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096
Apr 22 20:34:06 volumio ntfs-3g[12168]: Global ownership and permissions enforced, configuration type 7
Apr 22 20:34:06 volumio volumio[12117]: info: Mounting Device Музыка_500г_2
Apr 22 20:34:06 volumio sudo[12173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Музыка_500г_2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:34:06 volumio sudo[12173]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:06 volumio sudo[12156]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:06 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:34:06 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:34:06 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:34:06 volumio ntfs-3g[12176]: Version 2017.3.23AR.3 integrated FUSE 28
Apr 22 20:34:06 volumio ntfs-3g[12176]: Mounted /dev/sdb1 (Read-Write, label "Музыка 500г 2", NTFS 3.1)
Apr 22 20:34:06 volumio ntfs-3g[12176]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:34:06 volumio ntfs-3g[12176]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096
Apr 22 20:34:06 volumio ntfs-3g[12176]: Global ownership and permissions enforced, configuration type 7
Apr 22 20:34:06 volumio ntfs-3g[12176]: Unmounting /dev/sdb1 (Музыка 500г 2)
Apr 22 20:34:06 volumio sudo[12173]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:06 volumio volumio[12117]: info: Ignoring mount for partition: boot
Apr 22 20:34:06 volumio volumio[12117]: info: Ignoring mount for partition: volumio
Apr 22 20:34:06 volumio volumio[12117]: info: Ignoring mount for partition: volumio_data
Apr 22 20:34:06 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:34:06 volumio volumio[12117]: info: Loading plugin "volumio_command_line_client"...
Apr 22 20:34:06 volumio volumio[12117]: info: Loading plugin "upnp"...
Apr 22 20:34:06 volumio volumio[12117]: info: [1713818046724] Starting Upmpd Daemon
Apr 22 20:34:06 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:34:06 volumio volumio[12117]: info: Loading plugin "my_music"...
Apr 22 20:34:06 volumio volumio[12117]: info: Loading plugin "mpd"...
Apr 22 20:34:06 volumio volumio[12117]: info: Loading plugin "upnp_browser"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "alarm-clock"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "airplay_emulation"...
Apr 22 20:34:07 volumio volumio[12117]: info: Starting Shairport Sync
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "last_100"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "webradio"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "i2s_dacs"...
Apr 22 20:34:07 volumio volumio[12117]: info: I2S DAC not set, start Auto-detection
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "volumiodiscovery"...
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** For more information see
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:34:07 volumio volumio[12117]: *** WARNING *** For more information see
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** For more information see
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:34:07 volumio node[12117]: *** WARNING *** For more information see
Apr 22 20:34:07 volumio volumio[12117]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 22 20:34:07 volumio volumio[12117]: info: Discovery: Started advertising with name: Volumio
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "outputs"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "albumart"...
Apr 22 20:34:07 volumio volumio[12117]: info: Plugin example_plugin is not enabled
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "inputs"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "updater_comm"...
Apr 22 20:34:07 volumio volumio[12117]: info: Plugin mpdemulation is not enabled
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "rest_api"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading plugin "websocket"...
Apr 22 20:34:07 volumio volumio[12117]: info: Loading i18n strings for locale ru
Apr 22 20:34:07 volumio volumio[12117]: Updating browse sources language
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:34:07 volumio volumio[12117]: Forking 1 albumart workers
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::initPlayerControls
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: Express server listening on port 3000
Apr 22 20:34:07 volumio volumio[12117]: [Metrics] WebUI: 3s 49.46ms
Apr 22 20:34:07 volumio volumio[12117]: info: Setting Device type: x86
Apr 22 20:34:07 volumio volumio[12117]: info: CoreStateMachine::resetVolumioState
Apr 22 20:34:07 volumio volumio[12117]: info: CoreStateMachine::getcurrentVolume
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::volumioRetrievevolume
Apr 22 20:34:07 volumio volumio[12117]: info: CoreStateMachine::pushState
Apr 22 20:34:07 volumio volumio[12117]: info: CorePlayQueue::getTrack 0
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::volumioPushState
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Apr 22 20:34:07 volumio volumio[12117]: info: Completed loading Core Plugins
Apr 22 20:34:07 volumio volumio[12117]: info: Preparing to generate the ALSA configuration file
Apr 22 20:34:07 volumio volumio[12117]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds
Apr 22 20:34:07 volumio volumio[12117]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Apr 22 20:34:07 volumio volumio[12117]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1
Apr 22 20:34:07 volumio volumio[12117]: info: Reloading queue from file
Apr 22 20:34:07 volumio volumio[12117]: info: Asound.conf file unchanged, so no further update is needed
Apr 22 20:34:07 volumio volumio[12117]: info: Output device has changed, restarting MPD
Apr 22 20:34:07 volumio sudo[12198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 22 20:34:07 volumio volumio[12117]: info: Output device has changed, restarting Shairport Sync
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio sudo[12198]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:07 volumio sudo[12198]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:07 volumio sudo[12201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 22 20:34:07 volumio sudo[12201]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:07 volumio systemd[1]: Stopping Music Player Daemon...
Apr 22 20:34:07 volumio volumio[12117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:34:07 volumio volumio[12117]: info: ___________ START PLUGINS ___________
Apr 22 20:34:07 volumio volumio[12117]: info: ControllerMpd::onStart: Initializing MPD
Apr 22 20:34:07 volumio volumio[12117]: info: Creating MPD Configuration file
Apr 22 20:34:07 volumio sudo[12207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 22 20:34:07 volumio sudo[12207]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:34:07 volumio sudo[12207]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:34:07 volumio volumio[12117]: info: [1713818047794] CoreMusicLibrary::Adding element Медиа-серверы
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:07 volumio sudo[12210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 22 20:34:07 volumio systemd[1]: mpd.service: Succeeded.
Apr 22 20:34:07 volumio systemd[1]: Stopped Music Player Daemon.
Apr 22 20:34:07 volumio sudo[12210]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 22 20:34:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 22 20:34:07 volumio systemd[1]: mpd.service: Succeeded.
Apr 22 20:34:07 volumio systemd[1]: Stopped Music Player Daemon.
Apr 22 20:34:07 volumio volumio[12117]: Starting albumart workers
Apr 22 20:34:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 22 20:34:07 volumio volumio[12117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:07 volumio volumio[12117]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:34:07 volumio volumio[12117]: info: [1713818047949] CoreMusicLibrary::Adding element Last_100
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:34:07 volumio volumio[12117]: info: [1713818047961] CoreMusicLibrary::Adding element Webradio
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:34:07 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 22 20:34:07 volumio volumio[12117]: info: Volumio Calling Home
Apr 22 20:34:08 volumio volumio[12117]: info: CoreStateMachine::setRepeat true single undefined
Apr 22 20:34:08 volumio volumio[12117]: info: CoreStateMachine::pushState
Apr 22 20:34:08 volumio volumio[12117]: info: CorePlayQueue::getTrack 0
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::volumioPushState
Apr 22 20:34:08 volumio volumio[12117]: info: CoreStateMachine::setRandom false
Apr 22 20:34:08 volumio volumio[12117]: info: CoreStateMachine::pushState
Apr 22 20:34:08 volumio volumio[12117]: info: CorePlayQueue::getTrack 0
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::volumioPushState
Apr 22 20:34:08 volumio volumio[12117]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 2
Apr 22 20:34:08 volumio volumio[12117]: info: MPD Permissions set
Apr 22 20:34:08 volumio volumio[12117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 22 20:34:08 volumio mpd[12217]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 22 20:34:08 volumio mpd[12217]: output: No 'audio_output' defined in config file
Apr 22 20:34:08 volumio mpd[12217]: output: Successfully detected a sndio audio device
Apr 22 20:34:08 volumio mpd[12217]: zeroconf: No global port, disabling zeroconf
Apr 22 20:34:08 volumio systemd[1]: Started Music Player Daemon.
Apr 22 20:34:08 volumio sudo[12210]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:08 volumio sudo[12201]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:08 volumio volumio[12117]: info: Completed starting Core Plugins
Apr 22 20:34:08 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:08 volumio volumio[12117]: info: ----- MyVolumio plugins startup ----
Apr 22 20:34:08 volumio volumio[12117]: info: -------------------------------------------
Apr 22 20:34:08 volumio volumio[12117]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::volumioGetState
Apr 22 20:34:08 volumio volumio[12117]: info: CorePlayQueue::getTrack 0
Apr 22 20:34:08 volumio volumio[12117]: info: MPD running with PID12217
Apr 22 20:34:08 volumio volumio[12117]: ,establishing connection
Apr 22 20:34:08 volumio volumio[12117]: info: Starting Shairport Sync
Apr 22 20:34:08 volumio volumio[12117]: info: Starting Shairport Sync
Apr 22 20:34:08 volumio sudo[12250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:34:08 volumio sudo[12250]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 22 20:34:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 22 20:34:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio volumio[12117]: info: Starting Shairport Sync
Apr 22 20:34:08 volumio sudo[12253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:34:08 volumio sudo[12253]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio sudo[12250]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 22 20:34:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Apr 22 20:34:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 22 20:34:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio sudo[12253]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:08 volumio sudo[12257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:34:08 volumio sudo[12257]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 22 20:34:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 22 20:34:08 volumio volumio[12117]: info: Listing playlists
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::volumioGetQueue
Apr 22 20:34:08 volumio volumio[12117]: info: CoreStateMachine::getQueue
Apr 22 20:34:08 volumio volumio[12117]: info: CorePlayQueue::getQueue
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 22 20:34:08 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:34:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 22 20:34:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:34:08 volumio sudo[12257]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:08 volumio volumio-remote-updater[547]: [2024-04-22 20:34:08] [connect] Successful connection
Apr 22 20:34:08 volumio volumio[12117]: error: MPD error: The expression evaluated to a falsy value:
Apr 22 20:34:08 volumio volumio[12117]: assert.ok(self.idling)
Apr 22 20:34:08 volumio volumio[12117]: error: The expression evaluated to a falsy value:
Apr 22 20:34:08 volumio volumio[12117]: assert.ok(self.idling)
Apr 22 20:34:08 volumio volumio[12117]: error: MPD error: The expression evaluated to a falsy value:
Apr 22 20:34:08 volumio volumio[12117]: assert.ok(self.idling)
Apr 22 20:34:08 volumio volumio[12117]: error: The expression evaluated to a falsy value:
Apr 22 20:34:08 volumio volumio[12117]: assert.ok(self.idling)
Apr 22 20:34:08 volumio volumio[12117]: info: Shairport-Sync Started
Apr 22 20:34:08 volumio volumio[12117]: Error adding Membership: Error: addMembership EINVAL
Apr 22 20:34:08 volumio volumio[12117]: info: Shairport-Sync Started
Apr 22 20:34:08 volumio volumio[12117]: info: Shairport-Sync Started
Apr 22 20:34:08 volumio volumio[12117]: error: updateQueue error: null
Apr 22 20:34:08 volumio volumio-remote-updater[547]: [2024-04-22 20:34:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713818048 101
Apr 22 20:34:08 volumio volumio[12117]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4
Apr 22 20:34:09 volumio sudo[12150]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:12 volumio sudo[12265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:34:12 volumio sudo[12265]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:12 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:34:12 volumio sudo[12265]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:12 volumio volumio[12117]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds
Apr 22 20:34:12 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:34:12 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:34:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:34:13 volumio mpd[12217]: exception: No database
Apr 22 20:34:13 volumio mpd[12217]: exception: No database
Apr 22 20:34:13 volumio volumio[12117]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database
Apr 22 20:34:16 volumio sudo[12271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 22 20:34:16 volumio sudo[12271]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:16 volumio sudo[12271]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:16 volumio sudo[12274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 22 20:34:16 volumio sudo[12274]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:16 volumio sudo[12274]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:16 volumio sudo[12277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 22 20:34:16 volumio sudo[12277]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:16 volumio sudo[12277]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:16 volumio volumio[12117]: info: Upmpdcli Daemon Started
Apr 22 20:34:17 volumio sudo[12280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:34:17 volumio sudo[12280]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:17 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:34:18 volumio sudo[12280]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:18 volumio volumio[12117]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds
Apr 22 20:34:18 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:34:18 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:34:18 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:34:23 volumio sudo[12287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:34:23 volumio sudo[12287]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:23 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:34:23 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:34:23 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:34:23 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:34:23 volumio sudo[12287]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:23 volumio volumio[12117]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds
Apr 22 20:34:25 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:34:25 volumio volumio[12117]: info: Preload queue cleared
Apr 22 20:34:26 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:34:26 volumio volumio[12117]: info: Preload queue cleared
Apr 22 20:34:27 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:34:28 volumio volumio[12117]: info: Preload queue cleared
Apr 22 20:34:28 volumio volumio[12117]: error: MyVolumio Plugin failed to start in a timely fashion
Apr 22 20:34:28 volumio volumio[12117]: info: BOOT COMPLETED
Apr 22 20:34:28 volumio volumio[12117]: [Metrics] CommandRouter: 23s 903.06ms
Apr 22 20:34:28 volumio volumio[12117]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 22 20:34:28 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:34:28 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:34:28 volumio volumio[12117]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:34:28 volumio volumio[12117]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:34:28 volumio sudo[12295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:34:28 volumio sudo[12295]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:28 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:34:28 volumio sudo[12295]: pam_unix(sudo:session): session closed for user root
Apr 22 20:34:28 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:34:28 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:34:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:34:28 volumio volumio[12117]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 22 20:34:29 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 22 20:34:29 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 22 20:34:29 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 22 20:34:32 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:34:33 volumio volumio[12117]: info: Preload queue cleared
Apr 22 20:34:35 volumio volumio[12117]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:34:35 volumio volumio[12117]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 20:34:35 volumio volumio[12117]: TypeError: Cannot read property 'length' of undefined
Apr 22 20:34:35 volumio volumio[12117]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Apr 22 20:34:35 volumio volumio[12117]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Apr 22 20:34:35 volumio volumio[12117]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Apr 22 20:34:35 volumio volumio[12117]: at Parser.emit (events.js:400:28)
Apr 22 20:34:35 volumio volumio[12117]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Apr 22 20:34:35 volumio volumio[12117]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Apr 22 20:34:35 volumio volumio[12117]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Apr 22 20:34:35 volumio volumio[12117]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Apr 22 20:34:35 volumio volumio[12117]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Apr 22 20:34:35 volumio volumio[12117]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Apr 22 20:34:35 volumio volumio[12117]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Apr 22 20:34:35 volumio volumio[12117]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Apr 22 20:34:35 volumio volumio[12117]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Apr 22 20:34:35 volumio volumio[12117]: at IncomingMessage.emit (events.js:412:35)
Apr 22 20:34:35 volumio volumio[12117]: at endReadableNT (internal/streams/readable.js:1333:12)
Apr 22 20:34:35 volumio volumio[12117]: at processTicksAndRejections (internal/process/task_queues.js:82:21)
Apr 22 20:34:35 volumio volumio[12117]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 20:34:35 volumio sudo[12311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 20:33
Apr 22 20:34:35 volumio sudo[12311]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:34:35 volumio sudo[12311]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:04 volumio volumio-remote-updater[547]: [2024-04-22 20:35:04] [info] asio async_read_at_least error: system:104 (Connection reset by peer)
Apr 22 20:35:04 volumio volumio-remote-updater[547]: [2024-04-22 20:35:04] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error)
Apr 22 20:35:04 volumio volumio-remote-updater[547]: [2024-04-22 20:35:04] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006]
Apr 22 20:35:04 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Apr 22 20:35:04 volumio ntfs-3g[12168]: Unmounting /dev/sda1 (Музыка 500г)
Apr 22 20:35:04 volumio systemd[1]: media-\xd0\x9c\xd1\x83\xd0\xb7\xd1\x8b\xd0\xba\xd0\xb0_500\xd0\xb3.mount: Succeeded.
Apr 22 20:35:04 volumio systemd[1]: volumio.service: Failed with result 'exit-code'.
Apr 22 20:35:04 volumio systemd[1]: Started dynamicswap service.
Apr 22 20:35:04 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 22 20:35:04 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Apr 22 20:35:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2.
Apr 22 20:35:04 volumio systemd[1]: Started dynamicswap service.
Apr 22 20:35:04 volumio systemd[1]: Stopped Volumio Backend Module.
Apr 22 20:35:04 volumio systemd[1]: Started Volumio Backend Module.
Apr 22 20:35:04 volumio systemd[1]: dynamicswap.service: Succeeded.
Apr 22 20:35:05 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:05 volumio volumio[12383]: info: ----- Volumio3 ----
Apr 22 20:35:05 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:05 volumio volumio[12383]: info: ----- System startup ----
Apr 22 20:35:05 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:05 volumio volumio[12383]: info: MYVOLUMIO Environment detected
Apr 22 20:35:05 volumio volumio[12383]: info: Plugin folders cleanup
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning into folder /volumio/app/plugins/
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category audio_interface
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category miscellanea
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category music_service
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category plugins.json
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category system_controller
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning category user_interface
Apr 22 20:35:05 volumio volumio[12383]: info: Scanning into folder /data/plugins/
Apr 22 20:35:05 volumio volumio[12383]: info: Plugin folders cleanup completed
Apr 22 20:35:05 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:05 volumio volumio[12383]: info: ----- Core plugins startup ----
Apr 22 20:35:05 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugins from folder /volumio/app/plugins/
Apr 22 20:35:05 volumio volumio[12383]: info: Adding plugin upnp to MyMusic Plugins
Apr 22 20:35:05 volumio volumio[12383]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 22 20:35:05 volumio volumio[12383]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugins from folder /data/plugins/
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "system"...
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "appearance"...
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "network"...
Apr 22 20:35:05 volumio volumio[12383]: info: Refreshing Cached IP Addresses
Apr 22 20:35:05 volumio sudo[12410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 22 20:35:05 volumio sudo[12410]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:05 volumio sudo[12410]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:05 volumio sudo[12413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 22 20:35:05 volumio sudo[12413]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:05 volumio sudo[12413]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "services"...
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "alsa_controller"...
Apr 22 20:35:05 volumio sudo[12416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Apr 22 20:35:05 volumio sudo[12416]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:05 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "wizard"...
Apr 22 20:35:05 volumio volumio[12383]: info: Loading plugin "networkfs"...
Apr 22 20:35:05 volumio volumio[12383]: info: Starting Udev Watcher for removable devices
Apr 22 20:35:05 volumio sudo[12421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:35:05 volumio sudo[12421]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:05 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:35:05 volumio volumio[12383]: info: Mounting Device Музыка_500г
Apr 22 20:35:05 volumio sudo[12429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Музыка_500г -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:35:05 volumio sudo[12429]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:06 volumio sudo[12421]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:06 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:35:06 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:35:06 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:35:06 volumio sudo[12429]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:06 volumio ntfs-3g[12433]: Version 2017.3.23AR.3 integrated FUSE 28
Apr 22 20:35:06 volumio ntfs-3g[12433]: Mounted /dev/sda1 (Read-Write, label "Музыка 500г", NTFS 3.1)
Apr 22 20:35:06 volumio ntfs-3g[12433]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:35:06 volumio ntfs-3g[12433]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096
Apr 22 20:35:06 volumio ntfs-3g[12433]: Global ownership and permissions enforced, configuration type 7
Apr 22 20:35:06 volumio volumio[12383]: info: Mounting Device Музыка_500г_2
Apr 22 20:35:06 volumio volumio[12383]: /bin/mkdir: cannot create directory ‘/mnt/USB/Музыка_500г_2’: File exists
Apr 22 20:35:06 volumio volumio[12383]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Музыка_500г_2"
Apr 22 20:35:06 volumio volumio[12383]: /bin/mkdir: cannot create directory ‘/mnt/USB/Музыка_500г_2’: File exists
Apr 22 20:35:06 volumio sudo[12440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Музыка_500г_2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:35:06 volumio sudo[12440]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:06 volumio sudo[12440]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:06 volumio volumio[12383]: Mount is denied because the NTFS volume is already exclusively opened.
Apr 22 20:35:06 volumio volumio[12383]: The volume may be already mounted, or another software may use it which
Apr 22 20:35:06 volumio volumio[12383]: could be identified for example by the help of the 'fuser' command.
Apr 22 20:35:06 volumio volumio[12383]: error: Failed to mount Музыка_500г_2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/Музыка_500г_2" -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Apr 22 20:35:06 volumio volumio[12383]: Mount is denied because the NTFS volume is already exclusively opened.
Apr 22 20:35:06 volumio volumio[12383]: The volume may be already mounted, or another software may use it which
Apr 22 20:35:06 volumio volumio[12383]: could be identified for example by the help of the 'fuser' command.
Apr 22 20:35:06 volumio volumio[12383]: info: Ignoring mount for partition: boot
Apr 22 20:35:06 volumio volumio[12383]: info: Ignoring mount for partition: volumio
Apr 22 20:35:06 volumio volumio[12383]: info: Ignoring mount for partition: volumio_data
Apr 22 20:35:06 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "volumio_command_line_client"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "upnp"...
Apr 22 20:35:06 volumio volumio[12383]: info: [1713818106545] Starting Upmpd Daemon
Apr 22 20:35:06 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "my_music"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "mpd"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "upnp_browser"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "alarm-clock"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "airplay_emulation"...
Apr 22 20:35:06 volumio volumio[12383]: info: Starting Shairport Sync
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "last_100"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "webradio"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "i2s_dacs"...
Apr 22 20:35:06 volumio volumio[12383]: info: I2S DAC not set, start Auto-detection
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "volumiodiscovery"...
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** For more information see
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** For more information see
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 22 20:35:06 volumio volumio[12383]: *** WARNING *** For more information see
Apr 22 20:35:06 volumio node[12383]: *** WARNING *** For more information see
Apr 22 20:35:06 volumio volumio[12383]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 22 20:35:06 volumio volumio[12383]: info: Discovery: Started advertising with name: Volumio
Apr 22 20:35:06 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "outputs"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "albumart"...
Apr 22 20:35:06 volumio volumio[12383]: info: Plugin example_plugin is not enabled
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "inputs"...
Apr 22 20:35:06 volumio volumio[12383]: info: Loading plugin "updater_comm"...
Apr 22 20:35:07 volumio volumio[12383]: info: Plugin mpdemulation is not enabled
Apr 22 20:35:07 volumio volumio[12383]: info: Loading plugin "rest_api"...
Apr 22 20:35:07 volumio volumio[12383]: info: Loading plugin "websocket"...
Apr 22 20:35:07 volumio volumio[12383]: info: Loading i18n strings for locale ru
Apr 22 20:35:07 volumio volumio[12383]: Updating browse sources language
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:35:07 volumio volumio[12383]: Forking 1 albumart workers
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::initPlayerControls
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: Express server listening on port 3000
Apr 22 20:35:07 volumio volumio[12383]: [Metrics] WebUI: 2s 171.13ms
Apr 22 20:35:07 volumio volumio[12383]: info: Setting Device type: x86
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::resetVolumioState
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::getcurrentVolume
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioRetrievevolume
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:07 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Apr 22 20:35:07 volumio volumio[12383]: info: Completed loading Core Plugins
Apr 22 20:35:07 volumio volumio[12383]: info: Preparing to generate the ALSA configuration file
Apr 22 20:35:07 volumio volumio[12383]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds
Apr 22 20:35:07 volumio volumio[12383]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Apr 22 20:35:07 volumio volumio[12383]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 1
Apr 22 20:35:07 volumio volumio[12383]: info: Reloading queue from file
Apr 22 20:35:07 volumio volumio[12383]: info: Asound.conf file unchanged, so no further update is needed
Apr 22 20:35:07 volumio volumio[12383]: info: Output device has changed, restarting MPD
Apr 22 20:35:07 volumio volumio[12383]: info: Output device has changed, restarting Shairport Sync
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio sudo[12464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 22 20:35:07 volumio sudo[12466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 22 20:35:07 volumio sudo[12466]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:07 volumio sudo[12464]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:07 volumio sudo[12464]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:07 volumio systemd[1]: Stopping Music Player Daemon...
Apr 22 20:35:07 volumio volumio[12383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:35:07 volumio volumio[12383]: info: ___________ START PLUGINS ___________
Apr 22 20:35:07 volumio volumio[12383]: info: ControllerMpd::onStart: Initializing MPD
Apr 22 20:35:07 volumio volumio[12383]: info: Creating MPD Configuration file
Apr 22 20:35:07 volumio systemd[1]: mpd.service: Succeeded.
Apr 22 20:35:07 volumio systemd[1]: Stopped Music Player Daemon.
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 22 20:35:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:35:07 volumio volumio[12383]: info: [1713818107423] CoreMusicLibrary::Adding element Медиа-серверы
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:35:07 volumio sudo[12473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 22 20:35:07 volumio sudo[12473]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:07 volumio sudo[12473]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:07 volumio sudo[12476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 22 20:35:07 volumio sudo[12476]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: Starting albumart workers
Apr 22 20:35:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 22 20:35:07 volumio systemd[1]: mpd.service: Succeeded.
Apr 22 20:35:07 volumio systemd[1]: Stopped Music Player Daemon.
Apr 22 20:35:07 volumio volumio[12383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio systemd[1]: Starting Music Player Daemon...
Apr 22 20:35:07 volumio volumio[12383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:35:07 volumio volumio[12383]: info: [1713818107538] CoreMusicLibrary::Adding element Last_100
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 22 20:35:07 volumio volumio[12383]: info: [1713818107540] CoreMusicLibrary::Adding element Webradio
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 22 20:35:07 volumio volumio[12383]: info: Volumio Calling Home
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::setRepeat true single undefined
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:07 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::setRandom false
Apr 22 20:35:07 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:07 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:07 volumio volumio[12383]: info: MPD Permissions set
Apr 22 20:35:07 volumio volumio[12383]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Apr 22 20:35:07 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 22 20:35:07 volumio volumio[12383]: info: Starting Shairport Sync
Apr 22 20:35:07 volumio volumio[12383]: info: Starting Shairport Sync
Apr 22 20:35:08 volumio volumio[12383]: info: Starting Shairport Sync
Apr 22 20:35:08 volumio sudo[12510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:35:08 volumio sudo[12510]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:08 volumio sudo[12512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:35:08 volumio sudo[12512]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 22 20:35:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 22 20:35:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:35:08 volumio sudo[12516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 22 20:35:08 volumio sudo[12516]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:35:08 volumio sudo[12512]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:08 volumio sudo[12510]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:08 volumio volumio[12383]: info: Shairport-Sync Started
Apr 22 20:35:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 22 20:35:08 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Apr 22 20:35:08 volumio volumio[12383]: Error adding Membership: Error: addMembership EINVAL
Apr 22 20:35:08 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 22 20:35:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:35:08 volumio volumio[12383]: info: Shairport-Sync Started
Apr 22 20:35:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 22 20:35:08 volumio sudo[12516]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:08 volumio volumio[12383]: info: Shairport-Sync Started
Apr 22 20:35:08 volumio volumio[12383]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3
Apr 22 20:35:08 volumio mpd[12480]: Apr 22 20:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::volumioGetState
Apr 22 20:35:08 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:08 volumio systemd[1]: Started Music Player Daemon.
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::volumioGetState
Apr 22 20:35:08 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:08 volumio sudo[12476]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:08 volumio sudo[12466]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:08 volumio volumio[12383]: info: Completed starting Core Plugins
Apr 22 20:35:08 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:08 volumio volumio[12383]: info: ----- MyVolumio plugins startup ----
Apr 22 20:35:08 volumio volumio[12383]: info: -------------------------------------------
Apr 22 20:35:08 volumio volumio[12383]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 22 20:35:08 volumio volumio[12383]: info: Listing playlists
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::volumioGetQueue
Apr 22 20:35:08 volumio volumio[12383]: info: CoreStateMachine::getQueue
Apr 22 20:35:08 volumio volumio[12383]: info: CorePlayQueue::getQueue
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 22 20:35:08 volumio volumio[12383]: error: MPD error: The expression evaluated to a falsy value:
Apr 22 20:35:08 volumio volumio[12383]: assert.ok(self.idling)
Apr 22 20:35:08 volumio volumio[12383]: error: The expression evaluated to a falsy value:
Apr 22 20:35:08 volumio volumio[12383]: assert.ok(self.idling)
Apr 22 20:35:08 volumio volumio[12383]: info: MPD running with PID12480
Apr 22 20:35:08 volumio volumio[12383]: ,establishing connection
Apr 22 20:35:08 volumio volumio[12383]: error: MPD error: The expression evaluated to a falsy value:
Apr 22 20:35:08 volumio volumio[12383]: assert.ok(self.idling)
Apr 22 20:35:08 volumio volumio[12383]: error: The expression evaluated to a falsy value:
Apr 22 20:35:08 volumio volumio[12383]: assert.ok(self.idling)
Apr 22 20:35:08 volumio volumio[12383]: error: updateQueue error: null
Apr 22 20:35:08 volumio volumio[12383]: info: Discovery: adding ef5abcd4-700a-4f91-b866-4e893b09d8f3
Apr 22 20:35:08 volumio volumio[12383]: info: Discovery: Found device Volumio
Apr 22 20:35:08 volumio volumio[12383]: info: CoreCommandRouter::volumioGetState
Apr 22 20:35:08 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:09 volumio volumio-remote-updater[547]: [2024-04-22 20:35:09] [connect] Successful connection
Apr 22 20:35:09 volumio volumio-remote-updater[547]: [2024-04-22 20:35:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713818109 101
Apr 22 20:35:09 volumio volumio[12383]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4
Apr 22 20:35:10 volumio sudo[12416]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:12 volumio sudo[12529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:35:12 volumio sudo[12529]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:12 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:35:12 volumio sudo[12529]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:12 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:35:12 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:35:12 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:35:12 volumio volumio[12383]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds
Apr 22 20:35:13 volumio volumio[12383]: info: Volumio called home
Apr 22 20:35:13 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand update
Apr 22 20:35:13 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:13 volumio volumio[12383]: info:
Apr 22 20:35:13 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:13 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:13 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:13 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:13 volumio volumio[12383]: info:
Apr 22 20:35:13 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:13 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:13 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:14 volumio volumio[12383]: info:
Apr 22 20:35:14 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand update took 904 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 816 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 812 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 812 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 811 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:14 volumio volumio[12383]: info: Command Router : Notfying DB Updatetrue
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:14 volumio volumio[12383]: info: Command Router : Notfying DB Updatetrue
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:14 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:14 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: No code
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:14 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: No code
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: ------------------------------ 890ms
Apr 22 20:35:14 volumio volumio[12383]: info: ------------------------------ 888ms
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 98 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: info: sendMpdCommand status took 96 milliseconds
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:14 volumio volumio[12383]: info: Command Router : Notfying DB Updatetrue
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:35:14 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:14 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:14 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: No code
Apr 22 20:35:14 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:14 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:14 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:14 volumio volumio[12383]: info: ------------------------------ 141ms
Apr 22 20:35:16 volumio sudo[12542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 22 20:35:16 volumio sudo[12542]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:16 volumio sudo[12545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 22 20:35:16 volumio sudo[12545]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:16 volumio sudo[12542]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:16 volumio sudo[12545]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:16 volumio sudo[12548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Apr 22 20:35:16 volumio sudo[12548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:16 volumio sudo[12548]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:16 volumio volumio[12383]: info: Upmpdcli Daemon Started
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin multiroom to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 22 20:35:17 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:17 volumio volumio[12383]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 22 20:35:17 volumio volumio[12383]: info: MyVolumio login type: Token
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 22 20:35:17 volumio volumio[12383]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 22 20:35:18 volumio volumio[12383]: info: Starting Streaming Service Transparent Proxy
Apr 22 20:35:18 volumio volumio[12383]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 22 20:35:18 volumio volumio[12383]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 22 20:35:18 volumio volumio[12383]: info: Streaming services startup
Apr 22 20:35:18 volumio volumio[12383]: info: Starting Streaming Daemon
Apr 22 20:35:18 volumio sudo[12558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 22 20:35:18 volumio sudo[12558]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:18 volumio sudo[12558]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:18 volumio volumio[12383]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 22 20:35:18 volumio volumio[12383]: error: Cannot start Volumio Streaming Daemon
Apr 22 20:35:18 volumio volumio[12383]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 22 20:35:18 volumio volumio[12383]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 22 20:35:18 volumio sudo[12565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:35:18 volumio sudo[12565]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:18 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:35:18 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:35:18 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:35:18 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:35:18 volumio sudo[12565]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:18 volumio volumio[12383]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds
Apr 22 20:35:18 volumio volumio[12383]: STREAMING PROXY: Starting server on port 3245
Apr 22 20:35:18 volumio volumio[12383]: Node JS runtime: 14
Apr 22 20:35:19 volumio volumio[12383]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 22 20:35:20 volumio volumio[12383]: info: MyVolumio token set successfully
Apr 22 20:35:20 volumio volumio[12383]: info: MYVOLUMIO: Adding device
Apr 22 20:35:20 volumio volumio[12383]: info: MYVOLUMIO: Evaluating Server
Apr 22 20:35:21 volumio volumio[12383]: info: MyVolumio status changed
Apr 22 20:35:21 volumio volumio[12383]: info: Streaming services startup
Apr 22 20:35:21 volumio volumio[12383]: info: Starting Streaming Daemon
Apr 22 20:35:21 volumio volumio[12383]: info: Removing browser output: myVolumio user plan is not superstar
Apr 22 20:35:21 volumio volumio[12383]: info: Removing audio output:
Apr 22 20:35:21 volumio volumio[12383]: info: Stoppping Tunnel 1
Apr 22 20:35:21 volumio sudo[12588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 22 20:35:21 volumio sudo[12588]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:21 volumio sudo[12590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Apr 22 20:35:21 volumio sudo[12590]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:21 volumio sudo[12588]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:21 volumio sudo[12590]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:21 volumio volumio[12383]: error: Cannot start Volumio Streaming Daemon
Apr 22 20:35:21 volumio volumio[12383]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 22 20:35:21 volumio volumio[12383]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 22 20:35:21 volumio volumio[12383]: info: Remote SSH Stopped
Apr 22 20:35:23 volumio sudo[12595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:35:23 volumio sudo[12595]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:23 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:35:23 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:35:23 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:35:23 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:35:23 volumio sudo[12595]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:23 volumio volumio[12383]: info: Cannot mount NAS music at system boot, trial number 4 ,retrying in 5 seconds
Apr 22 20:35:26 volumio volumio[12383]: info: MPD Database updated - AlbumList cache refreshed
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: database
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info: MPD Database updated - AlbumList cache refreshed
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: database
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info: MPD Database updated - AlbumList cache refreshed
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: database
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info:
Apr 22 20:35:26 volumio volumio[12383]: ---------------------------- MPD announces state update: update
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:26 volumio volumio[12383]: info: ControllerMpd::getState
Apr 22 20:35:26 volumio volumio[12383]: verbose: ControllerMpd::sendMpdCommand status
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 940 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 940 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 940 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: info: Command Router : Notfying DB Updatefalse
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:27 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: No code
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:27 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: No code
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: ------------------------------ 1001ms
Apr 22 20:35:27 volumio volumio[12383]: info: ------------------------------ 1001ms
Apr 22 20:35:27 volumio volumio[12383]: info: Setting Geolocation for MyVolumio to eu4
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 1578 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 1579 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: info: sendMpdCommand status took 1578 milliseconds
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: info: Command Router : Notfying DB Updatefalse
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:27 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:27 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: No code
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:27 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: No code
Apr 22 20:35:27 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:27 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:27 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:27 volumio volumio[12383]: info: ------------------------------ 1627ms
Apr 22 20:35:27 volumio volumio[12383]: info: ------------------------------ 1628ms
Apr 22 20:35:28 volumio volumio[12383]: info: sendMpdCommand status took 2180 milliseconds
Apr 22 20:35:28 volumio volumio[12383]: info: sendMpdCommand status took 2180 milliseconds
Apr 22 20:35:28 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:28 volumio volumio[12383]: info: Command Router : Notfying DB Updatefalse
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::Close All Modals sent
Apr 22 20:35:28 volumio volumio[12383]: verbose: ControllerMpd::parseState
Apr 22 20:35:28 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:28 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: No code
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: ControllerMpd::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::servicePushState
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 22 20:35:28 volumio volumio[12383]: verbose: CURRENT POSITION 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::syncState stateService stop
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::syncState currentStatus stop
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: No code
Apr 22 20:35:28 volumio volumio[12383]: info: CoreStateMachine::pushState
Apr 22 20:35:28 volumio volumio[12383]: info: CorePlayQueue::getTrack 0
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::volumioPushState
Apr 22 20:35:28 volumio volumio[12383]: info: ------------------------------ 2225ms
Apr 22 20:35:28 volumio volumio[12383]: info: ------------------------------ 2225ms
Apr 22 20:35:28 volumio volumio[12383]: info: Updating MyVolumio device info
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:28 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 22 20:35:28 volumio volumio[12383]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 22 20:35:28 volumio sudo[12607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=WORKGROUP,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.8.105/F /mnt/NAS/music
Apr 22 20:35:28 volumio sudo[12607]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 22 20:35:28 volumio kernel: CIFS: Attempting to mount //192.168.8.105/F
Apr 22 20:35:29 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE
Apr 22 20:35:29 volumio kernel: CIFS: VFS: \\192.168.8.105 Send error in SessSetup = -13
Apr 22 20:35:29 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13
Apr 22 20:35:29 volumio sudo[12607]: pam_unix(sudo:session): session closed for user root
Apr 22 20:35:29 volumio volumio[12383]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Apr 22 20:35:29 volumio volumio[12383]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 22 20:35:32 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 22 20:35:32 volumio volumio[12383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 22 20:35:32 volumio volumio[12383]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 20:35:32 volumio volumio[12383]: TypeError: Cannot read property 'length' of undefined
Apr 22 20:35:32 volumio volumio[12383]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Apr 22 20:35:32 volumio volumio[12383]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Apr 22 20:35:32 volumio volumio[12383]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Apr 22 20:35:32 volumio volumio[12383]: at Parser.emit (events.js:400:28)
Apr 22 20:35:32 volumio volumio[12383]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Apr 22 20:35:32 volumio volumio[12383]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Apr 22 20:35:32 volumio volumio[12383]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Apr 22 20:35:32 volumio volumio[12383]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Apr 22 20:35:32 volumio volumio[12383]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Apr 22 20:35:32 volumio volumio[12383]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Apr 22 20:35:32 volumio volumio[12383]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Apr 22 20:35:32 volumio volumio[12383]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Apr 22 20:35:32 volumio volumio[12383]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Apr 22 20:35:32 volumio volumio[12383]: at IncomingMessage.emit (events.js:412:35)
Apr 22 20:35:32 volumio volumio[12383]: at endReadableNT (internal/streams/readable.js:1333:12)
Apr 22 20:35:32 volumio volumio[12383]: at processTicksAndRejections (internal/process/task_queues.js:82:21)
Apr 22 20:35:32 volumio volumio[12383]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 22 20:35:32 volumio sudo[12621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-22 20:34
Apr 22 20:35:32 volumio sudo[12621]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="b51559ff3f3719e35ff52cb9ccf2ff401b5ecb06"
VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee"
VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623"
VOLUMIO_BE_VERSION="9f81dc1810002a5ad8861a42d98d57bc06a5d5d3"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 17 Apr 2024 08:25:04 PM CEST"
VOLUMIO_VERSION="3.649"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="bb8d02f8f512a28dca8d2cfad424e542"